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/121.runsolver.aptitude aptitude -s -y --without-recommends remove libguile-ltdl-1 libjpeg62-dev libxrender1 kopete libxft2 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 22355 /proc/meminfo: memFree=904720/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=3152 CPUtime=0 /proc/22355/stat : 22355 (runsolver) R 22354 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28690529 3227648 32 18446744073709551615 134512640 134586868 4293135680 4293133728 4151903280 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22355/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.170129 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22355 /proc/meminfo: memFree=904720/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=89152 CPUtime=0.15 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 7775 1707 0 0 14 1 0 0 20 0 1 0 28690529 91291648 7575 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934193440 139894681266068 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22355/statm: 22288 7575 6664 1052 0 858 0 Current children cumulated CPU time (s) 0.15 Current children cumulated vsize (KiB) 89152 [startup+0.200288 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22355 /proc/meminfo: memFree=904720/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=89152 CPUtime=0.19 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 7870 1707 0 0 17 2 0 0 20 0 1 0 28690529 91291648 7662 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934191952 139894681274338 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22355/statm: 22288 7662 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 89152 [startup+0.300271 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22355 /proc/meminfo: memFree=904720/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=101864 CPUtime=0.29 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 11684 1707 0 0 26 3 0 0 20 0 1 0 28690529 104308736 10731 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934191544 139894689346172 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22355/statm: 25466 10731 6742 1052 0 4036 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 101864 [startup+0.70021 s] /proc/loadavg: 1.00 1.02 1.00 2/55 22355 /proc/meminfo: memFree=904720/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=113880 CPUtime=0.68 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 13631 1707 0 0 63 5 0 0 20 0 2 0 28690529 116613120 12004 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934193448 139894688631901 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22355/statm: 28470 12004 6959 1052 0 7040 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 113880 [startup+1.50024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22360 /proc/meminfo: memFree=884252/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=115264 CPUtime=1.43 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 13842 1707 0 0 138 5 0 0 20 0 2 0 28690529 118030336 12213 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934188880 139894689129057 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 5 0 0 /proc/22355/statm: 28816 12213 7034 1052 0 7386 0 [pid=22355/tid=22360] ppid=22354 vsize=115264 CPUtime=0 /proc/22355/task/22360/stat : 22360 (aptitude) S 22354 22355 17863 34816 17863 4202560 4 1707 0 0 0 0 0 0 20 0 2 0 28690574 118030336 12213 18446744073709551615 139894686420992 139894690728344 140734934202176 139894589827072 139894655165140 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.43 Current children cumulated vsize (KiB) 115264 [startup+3.10024 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22360 /proc/meminfo: memFree=868504/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=153972 CPUtime=3.02 /proc/22355/stat : 22355 (aptitude) S 22354 22355 17863 34816 17863 4202496 23816 1707 0 0 293 9 0 0 20 0 2 0 28690529 157667328 22186 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934193936 139894655166027 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 5 0 0 /proc/22355/statm: 38493 22186 7088 1052 0 17063 0 [pid=22355/tid=22360] ppid=22354 vsize=153972 CPUtime=1.28 /proc/22355/task/22360/stat : 22360 (aptitude) R 22354 22355 17863 34816 17863 4202560 9973 1707 0 0 124 4 0 0 20 0 2 0 28690574 157667328 22186 18446744073709551615 139894686420992 139894690728344 140734934202176 139894589817960 139894687476836 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.02 Current children cumulated vsize (KiB) 153972 Solver just ended. Dumping a history of the last processes samples [startup+3.20031 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22360 /proc/meminfo: memFree=868504/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=157156 CPUtime=3.12 /proc/22355/stat : 22355 (aptitude) S 22354 22355 17863 34816 17863 4202496 24613 1707 0 0 303 9 0 0 20 0 2 0 28690529 160927744 22983 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934193936 139894655166027 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 5 0 0 /proc/22355/statm: 39289 22983 7088 1052 0 17859 0 [pid=22355/tid=22360] ppid=22354 vsize=157156 CPUtime=1.38 /proc/22355/task/22360/stat : 22360 (aptitude) R 22354 22355 17863 34816 17863 4202560 10770 1707 0 0 134 4 0 0 20 0 2 0 28690574 160927744 22983 18446744073709551615 139894686420992 139894690728344 140734934202176 139894589817960 139894688991648 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.12 Current children cumulated vsize (KiB) 157156 [startup+4.00025 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22360 /proc/meminfo: memFree=838620/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=172480 CPUtime=3.91 /proc/22355/stat : 22355 (aptitude) S 22354 22355 17863 34816 17863 4202496 28420 1707 0 0 382 9 0 0 20 0 2 0 28690529 176619520 26790 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934193936 139894655166027 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 5 0 0 /proc/22355/statm: 43120 26790 7091 1052 0 21690 0 [pid=22355/tid=22360] ppid=22354 vsize=172480 CPUtime=2.17 /proc/22355/task/22360/stat : 22360 (aptitude) R 22354 22355 17863 34816 17863 4202560 14577 1707 0 0 213 4 0 0 20 0 2 0 28690574 176619520 26790 18446744073709551615 139894686420992 139894690728344 140734934202176 139894589817960 139894643931189 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.91 Current children cumulated vsize (KiB) 172480 [startup+4.40025 s] /proc/loadavg: 1.00 1.02 1.00 2/57 22360 /proc/meminfo: memFree=824236/1022884 swapFree=0/0 [pid=22355] ppid=22354 vsize=173724 CPUtime=4.31 /proc/22355/stat : 22355 (aptitude) R 22354 22355 17863 34816 17863 4202496 28821 1707 0 0 422 9 0 0 20 0 2 0 28690529 177893376 27188 18446744073709551615 139894686420992 139894690728344 140734934202176 140734934185576 139894681313616 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 5 0 0 /proc/22355/statm: 43431 27188 7147 1052 0 21994 0 [pid=22355/tid=22360] ppid=22354 vsize=173724 CPUtime=2.21 /proc/22355/task/22360/stat : 22360 (aptitude) S 22354 22355 17863 34816 17863 4202560 14749 1707 0 0 217 4 0 0 20 0 2 0 28690574 177893376 27188 18446744073709551615 139894686420992 139894690728344 140734934202176 139894589827072 139894655165140 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.31 Current children cumulated vsize (KiB) 173724 Child status: 0 Real time (s): 4.44412 CPU time (s): 4.37227 CPU user time (s): 4.24427 CPU system time (s): 0.128008 CPU usage (%): 98.3834 Max. virtual memory (cumulated for all children) (KiB): 173724 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.24427 system time used= 0.128008 maximum resident set size= 108768 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 30597 page faults= 0 swaps= 0 block input operations= 0 block output operations= 88 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 63 involuntary context switches= 122 runsolver used 0 second user time and 0.028001 second system time The end