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/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: 0.33 0.08 0.02 2/55 18775 /proc/meminfo: memFree=286436/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=3152 CPUtime=0 /proc/18775/stat : 18775 (runsolver) R 18774 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28163013 3227648 32 18446744073709551615 134512640 134586868 4287552176 4287550224 4151919664 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.185746 s] /proc/loadavg: 0.33 0.08 0.02 2/55 18775 /proc/meminfo: memFree=286436/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=88324 CPUtime=0.18 /proc/18775/stat : 18775 (aptitude) R 18774 18775 17863 34816 17863 4202496 6189 1710 0 0 16 1 0 1 20 0 2 0 28163013 90443776 5609 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760113864 140158535162976 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 22081 5609 3183 1052 0 4424 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 88324 [startup+0.200279 s] /proc/loadavg: 0.33 0.08 0.02 2/55 18775 /proc/meminfo: memFree=286436/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=88324 CPUtime=0.2 /proc/18775/stat : 18775 (aptitude) R 18774 18775 17863 34816 17863 4202496 6192 1710 0 0 18 1 0 1 20 0 2 0 28163013 90443776 5612 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760113864 140158542539869 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 22081 5612 3186 1052 0 4424 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 88324 [startup+0.300273 s] /proc/loadavg: 0.33 0.08 0.02 2/55 18775 /proc/meminfo: memFree=286436/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=88324 CPUtime=0.3 /proc/18775/stat : 18775 (aptitude) R 18774 18775 17863 34816 17863 4202496 6246 1710 0 0 28 1 0 1 20 0 2 0 28163013 90443776 5665 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760112944 140158535233840 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 22081 5665 3222 1052 0 4424 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 88324 [startup+0.700193 s] /proc/loadavg: 0.33 0.08 0.02 2/55 18775 /proc/meminfo: memFree=286436/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=92572 CPUtime=0.69 /proc/18775/stat : 18775 (aptitude) S 18774 18775 17863 34816 17863 4202496 7387 1710 0 0 66 2 0 1 20 0 2 0 28163013 94793728 6804 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760114352 140158509073995 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 23143 6804 3312 1052 0 5486 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 92572 [startup+1.50023 s] /proc/loadavg: 0.39 0.09 0.03 2/57 18780 /proc/meminfo: memFree=267828/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=100364 CPUtime=1.48 /proc/18775/stat : 18775 (aptitude) S 18774 18775 17863 34816 17863 4202496 9364 1710 0 0 145 2 0 1 20 0 2 0 28163013 102772736 8781 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760114352 140158509073995 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 25091 8781 3317 1052 0 7434 0 [pid=18775/tid=18780] ppid=18774 vsize=100364 CPUtime=0.95 /proc/18775/task/18780/stat : 18780 (aptitude) R 18774 18775 17863 34816 17863 4202560 3034 1710 0 0 92 2 0 1 20 0 2 0 28163029 102772736 8781 18446744073709551615 140158540328960 140158544636312 140736760122592 140158464491144 140158542899600 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.48 Current children cumulated vsize (KiB) 100364 Solver just ended. Dumping a history of the last processes samples [startup+1.60032 s] /proc/loadavg: 0.39 0.09 0.03 2/57 18780 /proc/meminfo: memFree=267828/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=101024 CPUtime=1.59 /proc/18775/stat : 18775 (aptitude) S 18774 18775 17863 34816 17863 4202496 9506 1710 0 0 155 3 0 1 20 0 2 0 28163013 103448576 8923 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760114352 140158509073995 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 25256 8923 3317 1052 0 7599 0 [pid=18775/tid=18780] ppid=18774 vsize=101024 CPUtime=1.04 /proc/18775/task/18780/stat : 18780 (aptitude) R 18774 18775 17863 34816 17863 4202560 3176 1710 0 0 101 2 0 1 20 0 2 0 28163029 103448576 8923 18446744073709551615 140158540328960 140158544636312 140736760122592 140158464491144 140158542975626 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) 101024 [startup+2.00024 s] /proc/loadavg: 0.39 0.09 0.03 2/57 18780 /proc/meminfo: memFree=267828/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=103932 CPUtime=1.98 /proc/18775/stat : 18775 (aptitude) S 18774 18775 17863 34816 17863 4202496 10255 1710 0 0 194 3 0 1 20 0 2 0 28163013 106426368 9672 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760114352 140158509073995 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 25983 9672 3317 1052 0 8326 0 [pid=18775/tid=18780] ppid=18774 vsize=103932 CPUtime=1.44 /proc/18775/task/18780/stat : 18780 (aptitude) R 18774 18775 17863 34816 17863 4202560 3925 1710 0 0 141 2 0 1 20 0 2 0 28163029 106426368 9672 18446744073709551615 140158540328960 140158544636312 140736760122592 140158464488984 140158498546728 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) 103932 [startup+2.20025 s] /proc/loadavg: 0.39 0.09 0.03 2/57 18780 /proc/meminfo: memFree=258032/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=106764 CPUtime=2.19 /proc/18775/stat : 18775 (aptitude) R 18774 18775 17863 34816 17863 4202496 11016 1710 0 0 214 4 0 1 20 0 2 0 28163013 109326336 10431 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760112680 140158497820186 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 26691 10431 3367 1052 0 9027 0 [pid=18775/tid=18780] ppid=18774 vsize=106764 CPUtime=1.59 /proc/18775/task/18780/stat : 18780 (aptitude) S 18774 18775 17863 34816 17863 4202560 4432 1710 0 0 156 2 0 1 20 0 2 0 28163029 109326336 10431 18446744073709551615 140158540328960 140158544636312 140736760122592 140158464497664 140158509073108 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.19 Current children cumulated vsize (KiB) 106764 [startup+2.30316 s] /proc/loadavg: 0.39 0.09 0.03 2/57 18780 /proc/meminfo: memFree=258032/1022884 swapFree=0/0 [pid=18775] ppid=18774 vsize=97028 CPUtime=2.29 /proc/18775/stat : 18775 (aptitude) R 18774 18775 17863 34816 17863 4202496 11024 1710 0 0 224 4 0 1 20 0 2 0 28163013 99356672 8004 18446744073709551615 140158540328960 140158544636312 140736760122592 140736760105992 140158535221613 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/18775/statm: 24257 8004 3374 1052 0 6593 0 [pid=18775/tid=18780] ppid=18774 vsize=97028 CPUtime=1.59 /proc/18775/task/18780/stat : 18780 (aptitude) S 18774 18775 17863 34816 17863 4202560 4432 1710 0 0 156 2 0 1 20 0 2 0 28163029 99356672 8004 18446744073709551615 140158540328960 140158544636312 140736760122592 140158464497664 140158509073108 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.29 Current children cumulated vsize (KiB) 97028 Child status: 0 Real time (s): 2.31453 CPU time (s): 2.30814 CPU user time (s): 2.24814 CPU system time (s): 0.060003 CPU usage (%): 99.7241 Max. virtual memory (cumulated for all children) (KiB): 106764 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.24814 system time used= 0.060003 maximum resident set size= 41724 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 12800 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= 59 involuntary context switches= 90 runsolver used 0 second user time and 0.016001 second system time The end