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/115.runsolver.aptitude aptitude -s -y --without-recommends remove libtunepimp2 kscreensaver-xsavers libfreetype6-dev kbackgammon python-gtk2 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 22304 /proc/meminfo: memFree=908068/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=3152 CPUtime=0 /proc/22304/stat : 22304 (runsolver) R 22303 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28688767 3227648 33 18446744073709551615 134512640 134586868 4290580704 4290578752 4151211056 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.138362 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22304 /proc/meminfo: memFree=908068/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=89152 CPUtime=0.13 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 7772 1709 0 0 10 2 1 0 20 0 1 0 28688767 91291648 7573 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221475216 139861511606216 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 22288 7573 6662 1052 0 858 0 Current children cumulated CPU time (s) 0.13 Current children cumulated vsize (KiB) 89152 [startup+0.20024 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22304 /proc/meminfo: memFree=908068/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=89152 CPUtime=0.19 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 7869 1709 0 0 16 2 1 0 20 0 1 0 28688767 91291648 7662 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221473728 139861511059426 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 89152 [startup+0.300237 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22304 /proc/meminfo: memFree=908068/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=101864 CPUtime=0.3 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 11526 1709 0 0 25 4 1 0 20 0 1 0 28688767 104308736 10574 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221473320 139861519131260 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 25466 10574 6742 1052 0 4036 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 101864 [startup+0.700225 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22304 /proc/meminfo: memFree=908068/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=113868 CPUtime=0.69 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 13631 1709 0 0 63 5 1 0 20 0 2 0 28688767 116600832 12003 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221475224 139861473716469 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/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 22309 /proc/meminfo: memFree=887600/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=115236 CPUtime=1.49 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 13835 1709 0 0 142 6 1 0 20 0 2 0 28688767 118001664 12205 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221470656 139861474358483 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 28809 12205 7033 1052 0 7379 0 [pid=22304/tid=22309] ppid=22303 vsize=115236 CPUtime=0.01 /proc/22304/task/22309/stat : 22309 (aptitude) S 22303 22304 17863 34816 17863 4202560 4 1709 0 0 0 0 1 0 20 0 2 0 28688812 118001664 12205 18446744073709551615 139861516206080 139861520513432 140735221483952 139861419612160 139861484950228 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 22309 /proc/meminfo: memFree=887600/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=115236 CPUtime=1.59 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 13835 1709 0 0 152 6 1 0 20 0 2 0 28688767 118001664 12205 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221470656 139861519233996 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 28809 12205 7033 1052 0 7379 0 [pid=22304/tid=22309] ppid=22303 vsize=115236 CPUtime=0.01 /proc/22304/task/22309/stat : 22309 (aptitude) S 22303 22304 17863 34816 17863 4202560 4 1709 0 0 0 0 1 0 20 0 2 0 28688812 118001664 12205 18446744073709551615 139861516206080 139861520513432 140735221483952 139861419612160 139861484950228 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.00027 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22309 /proc/meminfo: memFree=887600/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=123420 CPUtime=1.98 /proc/22304/stat : 22304 (aptitude) S 22303 22304 17863 34816 17863 4202496 16151 1709 0 0 191 6 1 0 20 0 2 0 28688767 126382080 14520 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221475712 139861484951115 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 30855 14520 7090 1052 0 9425 0 [pid=22304/tid=22309] ppid=22303 vsize=123420 CPUtime=0.26 /proc/22304/task/22309/stat : 22309 (aptitude) R 22303 22304 17863 34816 17863 4202560 2316 1709 0 0 25 0 1 0 20 0 2 0 28688812 126382080 14520 18446744073709551615 139861516206080 139861520513432 140735221483952 139861419603784 139861517834417 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.98 Current children cumulated vsize (KiB) 123420 [startup+2.40024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22309 /proc/meminfo: memFree=874952/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=128596 CPUtime=2.39 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 17512 1709 0 0 231 7 1 0 20 0 2 0 28688767 131682304 15879 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221473784 139861511054545 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 32149 15879 7140 1052 0 10712 0 [pid=22304/tid=22309] ppid=22303 vsize=128596 CPUtime=0.62 /proc/22304/task/22309/stat : 22309 (aptitude) S 22303 22304 17863 34816 17863 4202560 3615 1709 0 0 60 1 1 0 20 0 2 0 28688812 131682304 15879 18446744073709551615 139861516206080 139861520513432 140735221483952 139861419612160 139861484950228 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.39 Current children cumulated vsize (KiB) 128596 [startup+2.50024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22309 /proc/meminfo: memFree=874952/1022884 swapFree=0/0 [pid=22304] ppid=22303 vsize=128596 CPUtime=2.49 /proc/22304/stat : 22304 (aptitude) R 22303 22304 17863 34816 17863 4202496 17513 1709 0 0 241 7 1 0 20 0 2 0 28688767 131682304 15880 18446744073709551615 139861516206080 139861520513432 140735221483952 140735221476024 139861517916060 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22304/statm: 32149 15880 7141 1052 0 10712 0 [pid=22304/tid=22309] ppid=22303 vsize=128596 CPUtime=0.62 /proc/22304/task/22309/stat : 22309 (aptitude) S 22303 22304 17863 34816 17863 4202560 3615 1709 0 0 60 1 1 0 20 0 2 0 28688812 131682304 15880 18446744073709551615 139861516206080 139861520513432 140735221483952 139861419612160 139861484950228 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.49 Current children cumulated vsize (KiB) 128596 Child status: 0 Real time (s): 2.51898 CPU time (s): 2.51216 CPU user time (s): 2.42415 CPU system time (s): 0.088005 CPU usage (%): 99.7291 Max. virtual memory (cumulated for all children) (KiB): 128596 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.42415 system time used= 0.088005 maximum resident set size= 63532 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19289 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= 90 runsolver used 0.004 second user time and 0.012 second system time The end