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.cudf.sarge.log.runsolver ./p2cudf-paranoid-1.9 /home/misc2010/data/icse/sarge/rand986.sarge.cudf /home/misc2010/tmp/201012061533/p2cudf-paranoid-1.9/rand986.sarge.cudf.sarge.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.28 1.14 1.17 6/48 27149 /proc/meminfo: memFree=786976/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2572 CPUtime=0 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 358 0 0 0 0 0 0 0 23 0 1 0 876356230 2633728 275 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 0 4 65536 18446744071564457842 0 0 17 0 0 0 0 /proc/27147/statm: 643 275 234 194 0 29 0 [pid=27148] ppid=27147 vsize=2572 CPUtime=0 /proc/27148/stat : 27148 (p2cudf) R 27147 27146 2059 34821 2220 4202560 92 0 0 0 0 0 0 0 25 0 1 0 876356230 2633728 117 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/27148/statm: 643 117 75 194 0 29 0 [pid=27149] ppid=27148 vsize=2572 CPUtime=0 /proc/27149/stat : 27149 (p2cudf) R 27148 27146 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 1 0 876356230 2633728 42 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65538 0 65538 0 0 0 17 0 0 0 0 /proc/27149/statm: 643 42 0 194 0 29 0 [startup+0.135568 s] /proc/loadavg: 1.28 1.14 1.17 6/48 27149 /proc/meminfo: memFree=786976/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5152 [startup+0.21558 s] /proc/loadavg: 1.28 1.14 1.17 6/48 27149 /proc/meminfo: memFree=786976/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5152 [startup+0.315604 s] /proc/loadavg: 1.28 1.14 1.17 6/48 27149 /proc/meminfo: memFree=786976/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5152 [startup+0.715673 s] /proc/loadavg: 1.28 1.14 1.17 6/48 27149 /proc/meminfo: memFree=786976/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 5152 [startup+1.51588 s] /proc/loadavg: 1.42 1.17 1.18 3/57 27169 /proc/meminfo: memFree=741920/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=888816 CPUtime=1.48 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 19435 0 1 0 138 10 0 0 25 0 10 0 876356233 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 222204 16811 1732 10 0 219294 0 [pid=27160/tid=27161] ppid=27147 vsize=888816 CPUtime=0.63 /proc/27160/task/27161/stat : 27161 (java) R 27147 27146 2059 34821 2220 4202560 7769 0 1 0 58 5 0 0 25 0 10 0 876356234 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4149083536 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 15 0 10 0 876356235 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876356236 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876356236 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=888816 CPUtime=0.55 /proc/27160/task/27166/stat : 27166 (java) R 27147 27146 2059 34821 2220 4202560 8780 0 0 0 51 4 0 0 25 0 10 0 876356238 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4152299347 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=888816 CPUtime=0.28 /proc/27160/task/27167/stat : 27167 (java) R 27147 27146 2059 34821 2220 4202560 1773 0 0 0 28 0 0 0 22 0 10 0 876356238 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4148483748 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=888816 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) R 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876356238 910147584 16811 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 1.5 Current children cumulated vsize (KiB) 893968 [startup+3.1062 s] /proc/loadavg: 1.42 1.17 1.18 4/57 27169 /proc/meminfo: memFree=702052/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=892040 CPUtime=3.07 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 35085 0 1 0 295 12 0 0 25 0 10 0 876356233 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 223010 29398 1762 10 0 220100 0 [pid=27160/tid=27161] ppid=27147 vsize=892040 CPUtime=1.3 /proc/27160/task/27161/stat : 27161 (java) R 27147 27146 2059 34821 2220 4202560 19016 0 1 0 124 6 0 0 25 0 10 0 876356234 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4152330086 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 10 0 876356235 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876356236 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876356236 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=892040 CPUtime=0.98 /proc/27160/task/27166/stat : 27166 (java) R 27147 27146 2059 34821 2220 4202560 10150 0 0 0 94 4 0 0 24 0 10 0 876356238 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4152306326 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=892040 CPUtime=0.78 /proc/27160/task/27167/stat : 27167 (java) R 27147 27146 2059 34821 2220 4202560 4805 0 0 0 77 1 0 0 20 0 10 0 876356238 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4151461968 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876356238 913448960 29398 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.09 Current children cumulated vsize (KiB) 897192 Solver just ended. Dumping a history of the last processes samples [startup+3.20624 s] /proc/loadavg: 1.42 1.17 1.18 4/57 27169 /proc/meminfo: memFree=702052/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=892040 CPUtime=3.17 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 35105 0 1 0 305 12 0 0 25 0 10 0 876356233 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 223010 29418 1769 10 0 220100 0 [pid=27160/tid=27161] ppid=27147 vsize=892040 CPUtime=1.3 /proc/27160/task/27161/stat : 27161 (java) R 27147 27146 2059 34821 2220 4202560 19016 0 1 0 124 6 0 0 25 0 10 0 876356234 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4152330086 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 15 0 10 0 876356235 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 10 0 876356236 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 10 0 876356236 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=892040 CPUtime=1.04 /proc/27160/task/27166/stat : 27166 (java) R 27147 27146 2059 34821 2220 4202560 10165 0 0 0 100 4 0 0 25 0 10 0 876356238 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4147175428 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=892040 CPUtime=0.81 /proc/27160/task/27167/stat : 27167 (java) R 27147 27146 2059 34821 2220 4202560 4810 0 0 0 80 1 0 0 20 0 10 0 876356238 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4151462949 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 10 0 876356238 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=892040 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 10 0 876356238 913448960 29418 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 3.19 Current children cumulated vsize (KiB) 897192 [startup+4.80656 s] /proc/loadavg: 1.42 1.17 1.18 4/57 27169 /proc/meminfo: memFree=642060/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=897772 CPUtime=4.76 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 44396 0 1 0 464 12 0 0 25 0 11 0 876356233 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 224443 37358 1777 10 0 221533 0 [pid=27160/tid=27161] ppid=27147 vsize=897772 CPUtime=1.84 /proc/27160/task/27161/stat : 27161 (java) R 27147 27146 2059 34821 2220 4202560 25535 0 1 0 177 7 0 0 25 0 11 0 876356234 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 16 0 11 0 876356235 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 876356236 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 876356236 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=897772 CPUtime=1.54 /proc/27160/task/27166/stat : 27166 (java) S 27147 27146 2059 34821 2220 4202560 10408 0 0 0 150 4 0 0 25 0 11 0 876356238 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=897772 CPUtime=1.38 /proc/27160/task/27167/stat : 27167 (java) R 27147 27146 2059 34821 2220 4202560 7339 0 0 0 137 1 0 0 21 0 11 0 876356238 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4151873546 0 4 0 16800975 0 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=897772 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876356238 919318528 37358 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 4.78 Current children cumulated vsize (KiB) 902924 [startup+5.20625 s] /proc/loadavg: 1.42 1.17 1.18 4/57 27169 /proc/meminfo: memFree=642060/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=898096 CPUtime=5.12 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 44920 0 1 0 500 12 0 0 25 0 11 0 876356233 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 224524 37882 1789 10 0 221614 0 [pid=27160/tid=27161] ppid=27147 vsize=898096 CPUtime=1.93 /proc/27160/task/27161/stat : 27161 (java) S 27147 27146 2059 34821 2220 4202560 26012 0 1 0 186 7 0 0 25 0 11 0 876356234 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 16 0 11 0 876356235 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=898096 CPUtime=1.58 /proc/27160/task/27166/stat : 27166 (java) S 27147 27146 2059 34821 2220 4202560 10413 0 0 0 154 4 0 0 22 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=898096 CPUtime=1.61 /proc/27160/task/27167/stat : 27167 (java) S 27147 27146 2059 34821 2220 4202560 7373 0 0 0 160 1 0 0 21 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.14 Current children cumulated vsize (KiB) 903248 [startup+5.60627 s] /proc/loadavg: 1.31 1.15 1.18 2/58 27172 /proc/meminfo: memFree=638580/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=898096 CPUtime=5.12 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 44920 0 1 0 500 12 0 0 25 0 11 0 876356233 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 224524 37882 1789 10 0 221614 0 [pid=27160/tid=27161] ppid=27147 vsize=898096 CPUtime=1.93 /proc/27160/task/27161/stat : 27161 (java) S 27147 27146 2059 34821 2220 4202560 26012 0 1 0 186 7 0 0 25 0 11 0 876356234 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 16 0 11 0 876356235 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=898096 CPUtime=1.58 /proc/27160/task/27166/stat : 27166 (java) S 27147 27146 2059 34821 2220 4202560 10413 0 0 0 154 4 0 0 22 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=898096 CPUtime=1.61 /proc/27160/task/27167/stat : 27167 (java) S 27147 27146 2059 34821 2220 4202560 7373 0 0 0 160 1 0 0 21 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876356238 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27172] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27172/stat : 27172 (java) S 27147 27146 2059 34821 2220 4202560 0 0 0 0 0 0 0 0 25 0 11 0 876356746 919650304 37882 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.14 Current children cumulated vsize (KiB) 903248 [startup+5.70774 s] /proc/loadavg: 1.31 1.15 1.18 2/58 27172 /proc/meminfo: memFree=638580/1048576 swapFree=2078764/2097144 [pid=27146] ppid=27145 vsize=2576 CPUtime=0 /proc/27146/stat : 27146 (p2cudf-paranoid) S 27145 27146 2059 34821 2220 4202496 380 0 0 0 0 0 0 0 23 0 1 0 876356229 2637824 274 1283457024 134512640 135304128 4291364528 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27146/statm: 644 274 233 194 0 30 0 [pid=27147] ppid=27146 vsize=2576 CPUtime=0.02 /proc/27147/stat : 27147 (p2cudf) S 27146 27146 2059 34821 2220 4202496 394 2996 0 1 0 0 2 0 25 0 1 0 876356230 2637824 283 1283457024 134512640 135304128 4291487456 18446744073709551615 4294960130 0 65536 4 65538 18446744071564329979 0 0 17 0 0 0 0 /proc/27147/statm: 644 283 241 194 0 30 0 [pid=27160] ppid=27147 vsize=898096 CPUtime=5.16 /proc/27160/stat : 27160 (java) S 27147 27146 2059 34821 2220 4202496 44960 0 1 0 504 12 0 0 25 0 11 0 876356233 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446744073709551615 0 0 17 0 0 0 0 /proc/27160/statm: 224524 37922 1789 10 0 221614 0 [pid=27160/tid=27161] ppid=27147 vsize=898096 CPUtime=1.93 /proc/27160/task/27161/stat : 27161 (java) S 27147 27146 2059 34821 2220 4202560 26012 0 1 0 186 7 0 0 25 0 11 0 876356234 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27162] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27162/stat : 27162 (java) S 27147 27146 2059 34821 2220 4202560 5 0 0 0 0 0 0 0 16 0 11 0 876356235 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 0 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27163] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27163/stat : 27163 (java) S 27147 27146 2059 34821 2220 4202560 9 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27164] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27164/stat : 27164 (java) S 27147 27146 2059 34821 2220 4202560 4 0 0 0 0 0 0 0 25 0 11 0 876356236 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27165] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27165/stat : 27165 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27166] ppid=27147 vsize=898096 CPUtime=1.59 /proc/27160/task/27166/stat : 27166 (java) S 27147 27146 2059 34821 2220 4202560 10415 0 0 0 155 4 0 0 17 0 11 0 876356238 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27167] ppid=27147 vsize=898096 CPUtime=1.61 /proc/27160/task/27167/stat : 27167 (java) S 27147 27146 2059 34821 2220 4202560 7373 0 0 0 160 1 0 0 21 0 11 0 876356238 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27168] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27168/stat : 27168 (java) S 27147 27146 2059 34821 2220 4202560 1 0 0 0 0 0 0 0 25 0 11 0 876356238 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27169] ppid=27147 vsize=898096 CPUtime=0 /proc/27160/task/27169/stat : 27169 (java) S 27147 27146 2059 34821 2220 4202560 2 0 0 0 0 0 0 0 15 0 11 0 876356238 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4294960130 0 4 0 16800975 18446612132844133376 0 0 -1 0 0 0 0 [pid=27160/tid=27172] ppid=27147 vsize=898096 CPUtime=0.02 /proc/27160/task/27172/stat : 27172 (java) R 27147 27146 2059 34821 2220 4202560 38 0 0 0 2 0 0 0 20 0 11 0 876356746 919650304 37922 1283457024 134512640 134550932 4290159680 18446744073709551615 4095014154 0 4 0 16800975 0 0 0 -1 0 0 0 0 Current children cumulated CPU time (s) 5.18 Current children cumulated vsize (KiB) 903248 Child status: 0 Real time (s): 5.80236 CPU time (s): 5.25633 CPU user time (s): 5.10032 CPU system time (s): 0.156009 CPU usage (%): 90.5895 Max. virtual memory (cumulated for all children) (KiB): 903656 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.10032 system time used= 0.156009 maximum resident set size= 0 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 48868 page faults= 2 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 351 involuntary context switches= 362 runsolver used 0.012 second user time and 0 second system time The end