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/105.runsolver.aptitude aptitude -s -y --without-recommends remove libbonoboui2-common libtext-wrapi18n-perl gnome-desktop-data gsfonts libtextwrap1 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.07 1.04 1.00 2/55 22234 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=3152 CPUtime=0 /proc/22234/stat : 22234 (runsolver) R 22233 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28675092 3227648 32 18446744073709551615 134512640 134586868 4288149264 4288147312 4151497776 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.195685 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22234 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=89152 CPUtime=0.18 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 7777 1710 0 0 16 2 0 0 20 0 1 0 28675092 91291648 7577 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510014784 140543632572408 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 22288 7577 6666 1052 0 858 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 89152 [startup+0.200286 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22234 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=89152 CPUtime=0.19 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 7870 1710 0 0 17 2 0 0 20 0 1 0 28675092 91291648 7662 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510013296 140543632025570 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 89152 [startup+0.300263 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22234 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=101864 CPUtime=0.29 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 11706 1710 0 0 26 3 0 0 20 0 1 0 28675092 104308736 10753 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510012952 140543632072852 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 25466 10753 6743 1052 0 4036 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 101864 [startup+0.700198 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22234 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=113868 CPUtime=0.68 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 13632 1710 0 0 64 4 0 0 20 0 2 0 28675092 116600832 12003 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510014792 140543640183485 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 28467 12003 6964 1052 0 7037 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 113868 [startup+1.50023 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22239 /proc/meminfo: memFree=888716/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=115236 CPUtime=1.47 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 13836 1710 0 0 143 4 0 0 20 0 2 0 28675092 118001664 12205 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510010224 140543594672437 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 28809 12205 7033 1052 0 7379 0 [pid=22234/tid=22239] ppid=22233 vsize=115236 CPUtime=0 /proc/22234/task/22239/stat : 22239 (aptitude) S 22233 22234 17863 34816 17863 4202560 4 1710 0 0 0 0 0 0 20 0 2 0 28675136 118001664 12205 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540578304 140543605916372 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 115236 [startup+3.10024 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22239 /proc/meminfo: memFree=862924/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=158464 CPUtime=3.07 /proc/22234/stat : 22234 (aptitude) S 22233 22234 17863 34816 17863 4202496 24941 1710 0 0 300 7 0 0 20 0 2 0 28675092 162267136 23309 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510015280 140543605917259 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 39616 23309 7090 1052 0 18186 0 [pid=22234/tid=22239] ppid=22233 vsize=158464 CPUtime=1.34 /proc/22234/task/22239/stat : 22239 (aptitude) R 22233 22234 17863 34816 17863 4202560 11104 1710 0 0 132 2 0 0 20 0 2 0 28675136 162267136 23309 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540571960 140543639960272 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.07 Current children cumulated vsize (KiB) 158464 Solver just ended. Dumping a history of the last processes samples [startup+3.20031 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22239 /proc/meminfo: memFree=862924/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=160976 CPUtime=3.18 /proc/22234/stat : 22234 (aptitude) S 22233 22234 17863 34816 17863 4202496 25567 1710 0 0 310 8 0 0 20 0 2 0 28675092 164839424 23935 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510015280 140543605917259 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 40244 23935 7090 1052 0 18814 0 [pid=22234/tid=22239] ppid=22233 vsize=160976 CPUtime=1.45 /proc/22234/task/22239/stat : 22239 (aptitude) R 22233 22234 17863 34816 17863 4202560 11730 1710 0 0 142 3 0 0 20 0 2 0 28675136 164839424 23935 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540571912 140543639818522 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.18 Current children cumulated vsize (KiB) 160976 [startup+4.00024 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22239 /proc/meminfo: memFree=839612/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=173804 CPUtime=3.96 /proc/22234/stat : 22234 (aptitude) S 22233 22234 17863 34816 17863 4202496 28780 1710 0 0 388 8 0 0 20 0 2 0 28675092 177975296 27148 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510015280 140543605917259 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 43451 27148 7090 1052 0 22021 0 [pid=22234/tid=22239] ppid=22233 vsize=173804 CPUtime=2.25 /proc/22234/task/22239/stat : 22239 (aptitude) R 22233 22234 17863 34816 17863 4202560 14943 1710 0 0 221 4 0 0 20 0 2 0 28675136 177975296 27148 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540572008 140543594682795 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.96 Current children cumulated vsize (KiB) 173804 [startup+4.80024 s] /proc/loadavg: 1.06 1.03 1.00 2/57 22239 /proc/meminfo: memFree=824360/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=181812 CPUtime=4.76 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 30812 1710 0 0 467 9 0 0 20 0 2 0 28675092 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510013608 140543594663595 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 45453 29178 7140 1052 0 24016 0 [pid=22234/tid=22239] ppid=22233 vsize=181812 CPUtime=2.9 /proc/22234/task/22239/stat : 22239 (aptitude) S 22233 22234 17863 34816 17863 4202560 16673 1710 0 0 286 4 0 0 20 0 2 0 28675136 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540578304 140543605916372 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.76 Current children cumulated vsize (KiB) 181812 [startup+5.00024 s] /proc/loadavg: 1.06 1.03 1.00 2/57 22239 /proc/meminfo: memFree=824360/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=181812 CPUtime=4.95 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 30812 1710 0 0 486 9 0 0 20 0 2 0 28675092 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510013608 140543631861952 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 45453 29178 7140 1052 0 24016 0 [pid=22234/tid=22239] ppid=22233 vsize=181812 CPUtime=2.9 /proc/22234/task/22239/stat : 22239 (aptitude) S 22233 22234 17863 34816 17863 4202560 16673 1710 0 0 286 4 0 0 20 0 2 0 28675136 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540578304 140543605916372 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.95 Current children cumulated vsize (KiB) 181812 [startup+5.10024 s] /proc/loadavg: 1.06 1.03 1.00 2/57 22239 /proc/meminfo: memFree=824360/1022884 swapFree=0/0 [pid=22234] ppid=22233 vsize=181812 CPUtime=5.05 /proc/22234/stat : 22234 (aptitude) R 22233 22234 17863 34816 17863 4202496 30812 1710 0 0 496 9 0 0 20 0 2 0 28675092 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140733510011384 140543638893460 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22234/statm: 45453 29178 7140 1052 0 24016 0 [pid=22234/tid=22239] ppid=22233 vsize=181812 CPUtime=2.9 /proc/22234/task/22239/stat : 22239 (aptitude) S 22233 22234 17863 34816 17863 4202560 16673 1710 0 0 286 4 0 0 20 0 2 0 28675136 186175488 29178 18446744073709551615 140543637172224 140543641479576 140733510023520 140543540578304 140543605916372 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.05 Current children cumulated vsize (KiB) 181812 Child status: 0 Real time (s): 5.1819 CPU time (s): 5.15632 CPU user time (s): 5.03231 CPU system time (s): 0.124007 CPU usage (%): 99.5064 Max. virtual memory (cumulated for all children) (KiB): 181812 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.03231 system time used= 0.124007 maximum resident set size= 116756 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 32598 page faults= 0 swaps= 0 block input operations= 0 block output operations= 80 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 66 involuntary context switches= 137 runsolver used 0 second user time and 0.028001 second system time The end