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-squeeze/logs/127.runsolver.aptitude aptitude -s -y --without-recommends remove hotplug gstreamer0.8-plugin-apps gpgsm libgtkmm-2.4-1 liboggflac1 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 Current StackSize limit: 8192 KiB [startup+0.192321 s] /proc/loadavg: 1.08 1.08 1.04 2/55 24461 /proc/meminfo: memFree=865512/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=100508 CPUtime=0.18 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 10607 1710 0 0 16 2 0 0 20 0 1 0 29228437 102920192 10406 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861104 140285099107087 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 25127 10406 9231 1052 0 1122 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 100508 [startup+0.200281 s] /proc/loadavg: 1.08 1.08 1.04 2/55 24461 /proc/meminfo: memFree=865512/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=100508 CPUtime=0.19 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 10608 1710 0 0 17 2 0 0 20 0 1 0 29228437 102920192 10407 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861104 140285099104086 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 25127 10407 9232 1052 0 1122 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 100508 [startup+0.300259 s] /proc/loadavg: 1.08 1.08 1.04 2/55 24461 /proc/meminfo: memFree=865512/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=111108 CPUtime=0.29 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 12675 1710 0 0 27 2 0 0 20 0 1 0 29228437 113774592 12466 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861432 140285062480433 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 27777 12466 9302 1052 0 3772 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 111108 [startup+0.700202 s] /proc/loadavg: 1.08 1.08 1.04 2/55 24461 /proc/meminfo: memFree=865512/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=123288 CPUtime=0.68 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 17026 1710 0 0 61 7 0 0 20 0 2 0 29228437 126246912 14299 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923863464 140285062480584 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 30822 14299 9478 1052 0 6817 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 123288 [startup+1.50024 s] /proc/loadavg: 1.08 1.08 1.04 2/57 24466 /proc/meminfo: memFree=837728/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=131940 CPUtime=1.48 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 19299 1710 0 0 140 8 0 0 20 0 2 0 29228437 135106560 16568 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923856712 140285061773639 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 32985 16568 9569 1052 0 8980 0 [pid=24461/tid=24466] ppid=24460 vsize=131940 CPUtime=0 /proc/24461/task/24466/stat : 24466 (aptitude) S 24460 24461 17863 34816 17863 4202560 4 1710 0 0 0 0 0 0 20 0 2 0 29228502 135106560 16568 18446744073709551615 140285104263168 140285108570520 140733923869840 140284995090432 140285073007316 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) 131940 [startup+3.10024 s] /proc/loadavg: 1.08 1.08 1.04 2/57 24466 /proc/meminfo: memFree=836860/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=210848 CPUtime=3.07 /proc/24461/stat : 24461 (aptitude) S 24460 24461 17863 34816 17863 4202496 22737 1710 0 0 298 9 0 0 20 0 2 0 29228437 215908352 20005 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861600 140285073008203 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 52712 20005 9660 1052 0 28707 0 [pid=24461/tid=24466] ppid=24460 vsize=210848 CPUtime=0.54 /proc/24461/task/24466/stat : 24466 (aptitude) R 24460 24461 17863 34816 17863 4202560 3295 1710 0 0 53 1 0 0 20 0 2 0 29228502 215908352 20005 18446744073709551615 140285104263168 140285108570520 140733923869840 140284995080920 140285106909509 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) 210848 Solver just ended. Dumping a history of the last processes samples [startup+3.20031 s] /proc/loadavg: 1.08 1.08 1.04 2/57 24466 /proc/meminfo: memFree=836860/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=212036 CPUtime=3.17 /proc/24461/stat : 24461 (aptitude) S 24460 24461 17863 34816 17863 4202496 23034 1710 0 0 308 9 0 0 20 0 2 0 29228437 217124864 20302 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861600 140285073008203 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 53009 20302 9660 1052 0 29004 0 [pid=24461/tid=24466] ppid=24460 vsize=212036 CPUtime=0.64 /proc/24461/task/24466/stat : 24466 (aptitude) R 24460 24461 17863 34816 17863 4202560 3592 1710 0 0 63 1 0 0 20 0 2 0 29228502 217124864 20302 18446744073709551615 140285104263168 140285108570520 140733923869840 140284995082120 140285106896230 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.17 Current children cumulated vsize (KiB) 212036 [startup+3.60024 s] /proc/loadavg: 1.08 1.08 1.04 2/57 24466 /proc/meminfo: memFree=822352/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=213884 CPUtime=3.57 /proc/24461/stat : 24461 (aptitude) S 24460 24461 17863 34816 17863 4202496 23499 1710 0 0 347 10 0 0 20 0 2 0 29228437 219017216 20767 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923861600 140285073008203 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 53471 20767 9660 1052 0 29466 0 [pid=24461/tid=24466] ppid=24460 vsize=213884 CPUtime=1.04 /proc/24461/task/24466/stat : 24466 (aptitude) R 24460 24461 17863 34816 17863 4202560 4057 1710 0 0 103 1 0 0 20 0 2 0 29228502 219017216 20767 18446744073709551615 140285104263168 140285108570520 140733923869840 140284995081320 140285099086969 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.57 Current children cumulated vsize (KiB) 213884 [startup+3.80024 s] /proc/loadavg: 1.08 1.08 1.04 2/57 24466 /proc/meminfo: memFree=822352/1022884 swapFree=0/0 [pid=24461] ppid=24460 vsize=214176 CPUtime=3.77 /proc/24461/stat : 24461 (aptitude) R 24460 24461 17863 34816 17863 4202496 23607 1710 0 0 367 10 0 0 20 0 2 0 29228437 219316224 20873 18446744073709551615 140285104263168 140285108570520 140733923869840 140733923857704 140285106469543 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24461/statm: 53544 20873 9710 1052 0 29532 0 [pid=24461/tid=24466] ppid=24460 vsize=214176 CPUtime=1.08 /proc/24461/task/24466/stat : 24466 (aptitude) S 24460 24461 17863 34816 17863 4202560 4109 1710 0 0 106 2 0 0 20 0 2 0 29228502 219316224 20873 18446744073709551615 140285104263168 140285108570520 140733923869840 140284995090432 140285073007316 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.77 Current children cumulated vsize (KiB) 214176 Child status: 0 Real time (s): 3.86299 CPU time (s): 3.84824 CPU user time (s): 3.72423 CPU system time (s): 0.124007 CPU usage (%): 99.6181 Max. virtual memory (cumulated for all children) (KiB): 214176 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.72423 system time used= 0.124007 maximum resident set size= 83968 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 25509 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= 58 involuntary context switches= 112 runsolver used 0 second user time and 0.024001 second system time The end