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/104.runsolver.aptitude aptitude -s -y --without-recommends remove libatk1.0-dev libkpimidentities1 kdebase-bin libvte4 openoffice.org-debian-files 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 22227 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=3152 CPUtime=0 /proc/22227/stat : 22227 (runsolver) R 22226 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28674851 3227648 33 18446744073709551615 134512640 134586868 4294566768 4294564816 4152063024 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.126436 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22227 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=89152 CPUtime=0.12 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 7769 1709 0 0 9 2 1 0 20 0 1 0 28674851 91291648 7568 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320924320 140302003345238 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 22288 7568 6656 1052 0 858 0 Current children cumulated CPU time (s) 0.12 Current children cumulated vsize (KiB) 89152 [startup+0.200336 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22227 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=89152 CPUtime=0.19 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 7872 1709 0 0 16 2 1 0 20 0 1 0 28674851 91291648 7663 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320922832 140302003357666 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 22288 7663 6726 1052 0 858 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 89152 [startup+0.300257 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22227 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=101864 CPUtime=0.3 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 11484 1709 0 0 25 4 1 0 20 0 1 0 28674851 104308736 10530 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320922424 140302011429500 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 25466 10530 6742 1052 0 4036 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 101864 [startup+0.7002 s] /proc/loadavg: 1.07 1.04 1.00 2/55 22227 /proc/meminfo: memFree=909184/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=113868 CPUtime=0.69 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 13631 1709 0 0 63 5 1 0 20 0 2 0 28674851 116600832 12001 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320924328 140302011515748 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 28467 12001 6961 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.07 1.04 1.00 2/57 22232 /proc/meminfo: memFree=888716/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=115240 CPUtime=1.49 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 13838 1709 0 0 142 6 1 0 20 0 2 0 28674851 118005760 12207 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320919760 140302009333309 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 28810 12207 7034 1052 0 7380 0 [pid=22227/tid=22232] ppid=22226 vsize=115240 CPUtime=0.01 /proc/22227/task/22232/stat : 22232 (aptitude) S 22226 22227 17863 34816 17863 4202560 4 1709 0 0 0 0 1 0 20 0 2 0 28674896 118005760 12207 18446744073709551615 140302008504320 140302012811672 140733320933056 140301911910400 140301977248468 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) 115240 Solver just ended. Dumping a history of the last processes samples [startup+1.60032 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22232 /proc/meminfo: memFree=888716/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=115240 CPUtime=1.59 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 13838 1709 0 0 152 6 1 0 20 0 2 0 28674851 118005760 12207 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320919760 140302010705742 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 28810 12207 7034 1052 0 7380 0 [pid=22227/tid=22232] ppid=22226 vsize=115240 CPUtime=0.01 /proc/22227/task/22232/stat : 22232 (aptitude) S 22226 22227 17863 34816 17863 4202560 4 1709 0 0 0 0 1 0 20 0 2 0 28674896 118005760 12207 18446744073709551615 140302008504320 140302012811672 140733320933056 140301911910400 140301977248468 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) 115240 [startup+2.00023 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22232 /proc/meminfo: memFree=888716/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=120520 CPUtime=1.99 /proc/22227/stat : 22227 (aptitude) S 22226 22227 17863 34816 17863 4202496 15442 1709 0 0 191 7 1 0 20 0 2 0 28674851 123412480 13810 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320924816 140301977249355 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 30130 13810 7090 1052 0 8700 0 [pid=22227/tid=22232] ppid=22226 vsize=120520 CPUtime=0.27 /proc/22227/task/22232/stat : 22232 (aptitude) R 22226 22227 17863 34816 17863 4202560 1604 1709 0 0 25 1 1 0 20 0 2 0 28674896 123412480 13810 18446744073709551615 140302008504320 140302012811672 140733320933056 140301911905896 140301966656704 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.99 Current children cumulated vsize (KiB) 120520 [startup+2.20024 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22232 /proc/meminfo: memFree=880160/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=122500 CPUtime=2.19 /proc/22227/stat : 22227 (aptitude) S 22226 22227 17863 34816 17863 4202496 15940 1709 0 0 211 7 1 0 20 0 2 0 28674851 125440000 14308 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320924816 140301977249355 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 30625 14308 7090 1052 0 9195 0 [pid=22227/tid=22232] ppid=22226 vsize=122500 CPUtime=0.47 /proc/22227/task/22232/stat : 22232 (aptitude) R 22226 22227 17863 34816 17863 4202560 2102 1709 0 0 45 1 1 0 20 0 2 0 28674896 125440000 14308 18446744073709551615 140302008504320 140302012811672 140733320933056 140301911904312 140302010715229 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) 122500 [startup+2.30319 s] /proc/loadavg: 1.07 1.04 1.00 2/57 22232 /proc/meminfo: memFree=880160/1022884 swapFree=0/0 [pid=22227] ppid=22226 vsize=122660 CPUtime=2.28 /proc/22227/stat : 22227 (aptitude) R 22226 22227 17863 34816 17863 4202496 16034 1709 0 0 220 7 1 0 20 0 2 0 28674851 125603840 14400 18446744073709551615 140302008504320 140302012811672 140733320933056 140733320920920 140302010338982 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/22227/statm: 30665 14400 7139 1052 0 9228 0 [pid=22227/tid=22232] ppid=22226 vsize=122660 CPUtime=0.5 /proc/22227/task/22232/stat : 22232 (aptitude) S 22226 22227 17863 34816 17863 4202560 2132 1709 0 0 48 1 1 0 20 0 2 0 28674896 125603840 14400 18446744073709551615 140302008504320 140302012811672 140733320933056 140301911910400 140301977248468 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.28 Current children cumulated vsize (KiB) 122660 Child status: 0 Real time (s): 2.38887 CPU time (s): 2.38015 CPU user time (s): 2.28814 CPU system time (s): 0.092005 CPU usage (%): 99.6349 Max. virtual memory (cumulated for all children) (KiB): 122660 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.28814 system time used= 0.092005 maximum resident set size= 57616 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17811 page faults= 0 swaps= 0 block input operations= 0 block output operations= 8 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 55 involuntary context switches= 90 runsolver used 0 second user time and 0.016001 second system time The end