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 -W 300 -M 1124 -w /home/cbse/results/sarge-etch-lenny/logs/113.runsolver.aptitude aptitude -s -y --without-recommends remove libjpeg-progs libwmf0.2-7 kate evolution-data-server xlibmesa-glu-dev Enforcing wall clock limit (soft limit, will send signal-name then SIGKILL): 300 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.00 1.02 1.00 2/55 22290 /proc/meminfo: memFree=908192/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=3152 CPUtime=0 /proc/22290/stat : 22290 (runsolver) R 22289 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28688266 3227648 33 18446744073709551615 134512640 134586868 4294660240 4294658288 4151526448 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.115022 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22290 /proc/meminfo: memFree=908192/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=89152 CPUtime=0.11 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 7763 1707 0 0 7 3 1 0 20 0 1 0 28688266 91291648 7564 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442194672 140325328165408 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 22288 7564 6653 1052 0 858 0 Current children cumulated CPU time (s) 0.11 Current children cumulated vsize (KiB) 89152 [startup+0.20029 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22290 /proc/meminfo: memFree=908192/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=89152 CPUtime=0.2 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 7869 1707 0 0 16 3 1 0 20 0 1 0 28688266 91291648 7662 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442193184 140325328290744 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 89152 [startup+0.300271 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22290 /proc/meminfo: memFree=908192/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=101864 CPUtime=0.29 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 11708 1707 0 0 24 4 1 0 20 0 1 0 28688266 104308736 10756 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442192840 140325328342906 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 25466 10756 6746 1052 0 4036 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 101864 [startup+0.700199 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22290 /proc/meminfo: memFree=908192/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=113868 CPUtime=0.69 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 13631 1707 0 0 63 5 1 0 20 0 2 0 28688266 116600832 12003 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442194680 140325299747806 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 28467 12003 6964 1052 0 7037 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 113868 [startup+1.50023 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22295 /proc/meminfo: memFree=887724/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=115236 CPUtime=1.49 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 13835 1707 0 0 142 6 1 0 20 0 2 0 28688266 118001664 12205 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442190112 140325299356672 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 28809 12205 7034 1052 0 7379 0 [pid=22290/tid=22295] ppid=22289 vsize=115236 CPUtime=0.01 /proc/22290/task/22295/stat : 22295 (aptitude) S 22289 22290 17863 34816 17863 4202560 4 1707 0 0 0 0 1 0 20 0 2 0 28688311 118001664 12205 18446744073709551615 140325333442560 140325337749912 140734442203408 140325236848640 140325302186708 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.49 Current children cumulated vsize (KiB) 115236 Solver just ended. Dumping a history of the last processes samples [startup+1.60033 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22295 /proc/meminfo: memFree=887724/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=115236 CPUtime=1.59 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 13835 1707 0 0 152 6 1 0 20 0 2 0 28688266 118001664 12205 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442190112 140325290952840 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 28809 12205 7034 1052 0 7379 0 [pid=22290/tid=22295] ppid=22289 vsize=115236 CPUtime=0.01 /proc/22290/task/22295/stat : 22295 (aptitude) S 22289 22290 17863 34816 17863 4202560 4 1707 0 0 0 0 1 0 20 0 2 0 28688311 118001664 12205 18446744073709551615 140325333442560 140325337749912 140734442203408 140325236848640 140325302186708 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.59 Current children cumulated vsize (KiB) 115236 [startup+2.00024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22295 /proc/meminfo: memFree=887724/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=122892 CPUtime=1.99 /proc/22290/stat : 22290 (aptitude) S 22289 22290 17863 34816 17863 4202496 16027 1707 0 0 192 6 1 0 20 0 2 0 28688266 125841408 14396 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442195168 140325302187595 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 30723 14396 7090 1052 0 9293 0 [pid=22290/tid=22295] ppid=22289 vsize=122892 CPUtime=0.27 /proc/22290/task/22295/stat : 22295 (aptitude) R 22289 22290 17863 34816 17863 4202560 2192 1707 0 0 26 0 1 0 20 0 2 0 28688311 125841408 14396 18446744073709551615 140325333442560 140325337749912 140734442203408 140325236839528 140325335072256 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.99 Current children cumulated vsize (KiB) 122892 [startup+2.40024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22295 /proc/meminfo: memFree=876688/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=192680 CPUtime=2.38 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 17168 1707 0 0 231 6 1 0 20 0 2 0 28688266 197304320 15535 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442193240 140325328296874 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 48170 15535 7140 1052 0 26733 0 [pid=22290/tid=22295] ppid=22289 vsize=192680 CPUtime=0.63 /proc/22290/task/22295/stat : 22295 (aptitude) S 22289 22290 17863 34816 17863 4202560 3272 1707 0 0 62 0 1 0 20 0 2 0 28688311 197304320 15535 18446744073709551615 140325333442560 140325337749912 140734442203408 140325236848640 140325302186708 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.38 Current children cumulated vsize (KiB) 192680 [startup+2.50025 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22295 /proc/meminfo: memFree=876688/1022884 swapFree=0/0 [pid=22290] ppid=22289 vsize=192680 CPUtime=2.48 /proc/22290/stat : 22290 (aptitude) R 22289 22290 17863 34816 17863 4202496 17258 1707 0 0 241 6 1 0 20 0 2 0 28688266 197304320 15561 18446744073709551615 140325333442560 140325337749912 140734442203408 140734442195512 140325290933842 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22290/statm: 48170 15561 7141 1052 0 26733 0 [pid=22290/tid=22295] ppid=22289 vsize=192680 CPUtime=0.63 /proc/22290/task/22295/stat : 22295 (aptitude) S 22289 22290 17863 34816 17863 4202560 3272 1707 0 0 62 0 1 0 20 0 2 0 28688311 197304320 15561 18446744073709551615 140325333442560 140325337749912 140734442203408 140325236848640 140325302186708 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.48 Current children cumulated vsize (KiB) 192680 Child status: 0 Real time (s): 2.52219 CPU time (s): 2.51216 CPU user time (s): 2.43215 CPU system time (s): 0.080005 CPU usage (%): 99.6024 Max. virtual memory (cumulated for all children) (KiB): 192680 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.43215 system time used= 0.080005 maximum resident set size= 62480 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19032 page faults= 0 swaps= 0 block input operations= 0 block output operations= 0 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 55 involuntary context switches= 91 runsolver used 0 second user time and 0.016001 second system time The end