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-sid/logs/108.runsolver.aptitude aptitude -s -y --without-recommends remove kde-core ssh gnome-applets-data knewsticker-scripts imagemagick 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.03 1.07 1.07 2/55 26635 /proc/meminfo: memFree=832608/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=3152 CPUtime=0 /proc/26635/stat : 26635 (runsolver) R 26634 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 29893142 3227648 33 18446744073709551615 134512640 134586868 4293981088 4293979136 4151886896 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.110539 s] /proc/loadavg: 1.03 1.07 1.07 2/55 26635 /proc/meminfo: memFree=832608/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=103288 CPUtime=0.11 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 11103 1709 0 0 8 2 0 1 20 0 1 0 29893142 105766912 10903 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028201904 140176318908240 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 25822 10903 9691 1052 0 1159 0 Current children cumulated CPU time (s) 0.11 Current children cumulated vsize (KiB) 103288 [startup+0.200282 s] /proc/loadavg: 1.03 1.07 1.07 2/55 26635 /proc/meminfo: memFree=832608/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=103288 CPUtime=0.2 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 11301 1709 0 0 17 2 0 1 20 0 1 0 29893142 105766912 11101 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028201904 140176281763279 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 25822 11101 9889 1052 0 1159 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 103288 [startup+0.300269 s] /proc/loadavg: 1.03 1.07 1.07 2/55 26635 /proc/meminfo: memFree=832608/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=103432 CPUtime=0.3 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 11428 1709 0 0 27 2 0 1 20 0 1 0 29893142 105914368 11220 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028200416 140176319076322 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 25858 11220 9956 1052 0 1195 0 Current children cumulated CPU time (s) 0.3 Current children cumulated vsize (KiB) 103432 [startup+0.700203 s] /proc/loadavg: 1.03 1.07 1.07 2/55 26635 /proc/meminfo: memFree=832608/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=123068 CPUtime=0.69 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 17689 1709 0 0 63 5 0 1 20 0 1 0 29893142 126021632 15965 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028200072 140176319123585 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 30767 15965 9976 1052 0 6104 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 123068 [startup+1.50031 s] /proc/loadavg: 1.03 1.07 1.07 2/57 26640 /proc/meminfo: memFree=803584/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=135856 CPUtime=1.48 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 20627 1709 0 0 140 7 0 1 20 0 2 0 29893142 139116544 17543 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028197752 140176281718686 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 33964 17543 10226 1052 0 9301 0 [pid=26635/tid=26640] ppid=26634 vsize=135856 CPUtime=0.01 /proc/26635/task/26640/stat : 26640 (aptitude) S 26634 26635 17863 34816 17863 4202560 4 1709 0 0 0 0 0 1 20 0 2 0 29893219 139116544 17543 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211900416 140176292967124 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) 135856 [startup+3.10024 s] /proc/loadavg: 1.03 1.07 1.07 2/57 26640 /proc/meminfo: memFree=802592/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=146016 CPUtime=3.08 /proc/26635/stat : 26635 (aptitude) S 26634 26635 17863 34816 17863 4202496 23270 1709 0 0 298 9 0 1 20 0 2 0 29893142 149520384 20185 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028202400 140176292968011 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 36504 20185 10319 1052 0 11841 0 [pid=26635/tid=26640] ppid=26634 vsize=146016 CPUtime=0.28 /proc/26635/task/26640/stat : 26640 (aptitude) R 26634 26635 17863 34816 17863 4202560 2486 1709 0 0 26 1 0 1 20 0 2 0 29893219 149520384 20185 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211889720 140176326839168 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.08 Current children cumulated vsize (KiB) 146016 Solver just ended. Dumping a history of the last processes samples [startup+3.20031 s] /proc/loadavg: 1.03 1.07 1.07 2/57 26640 /proc/meminfo: memFree=802592/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=148392 CPUtime=3.18 /proc/26635/stat : 26635 (aptitude) S 26634 26635 17863 34816 17863 4202496 23876 1709 0 0 308 9 0 1 20 0 2 0 29893142 151953408 20791 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028202400 140176292968011 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 37098 20791 10319 1052 0 12435 0 [pid=26635/tid=26640] ppid=26634 vsize=148392 CPUtime=0.38 /proc/26635/task/26640/stat : 26640 (aptitude) R 26634 26635 17863 34816 17863 4202560 3092 1709 0 0 36 1 0 1 20 0 2 0 29893219 151953408 20791 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211891512 140176290148720 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) 148392 [startup+4.00024 s] /proc/loadavg: 1.02 1.07 1.07 2/57 26640 /proc/meminfo: memFree=788580/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=158308 CPUtime=3.97 /proc/26635/stat : 26635 (aptitude) S 26634 26635 17863 34816 17863 4202496 26329 1709 0 0 386 10 0 1 20 0 2 0 29893142 162107392 23244 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028202400 140176292968011 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 39577 23244 10320 1052 0 14914 0 [pid=26635/tid=26640] ppid=26634 vsize=158308 CPUtime=1.17 /proc/26635/task/26640/stat : 26640 (aptitude) R 26634 26635 17863 34816 17863 4202560 5544 1709 0 0 114 2 0 1 20 0 2 0 29893219 162107392 23244 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211891736 140176326855898 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.97 Current children cumulated vsize (KiB) 158308 [startup+4.40024 s] /proc/loadavg: 1.02 1.07 1.07 2/57 26640 /proc/meminfo: memFree=777048/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=161872 CPUtime=4.37 /proc/26635/stat : 26635 (aptitude) S 26634 26635 17863 34816 17863 4202496 27228 1709 0 0 426 10 0 1 20 0 2 0 29893142 165756928 24143 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028202400 140176292968011 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 40468 24143 10320 1052 0 15805 0 [pid=26635/tid=26640] ppid=26634 vsize=161872 CPUtime=1.58 /proc/26635/task/26640/stat : 26640 (aptitude) R 26634 26635 17863 34816 17863 4202560 6443 1709 0 0 154 3 0 1 20 0 2 0 29893219 165756928 24143 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211892104 140176281723250 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.37 Current children cumulated vsize (KiB) 161872 [startup+4.60025 s] /proc/loadavg: 1.02 1.07 1.07 2/57 26640 /proc/meminfo: memFree=777048/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=162824 CPUtime=4.57 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202496 27511 1709 0 0 446 10 0 1 20 0 2 0 29893142 166731776 24424 18446744073709551615 140176324222976 140176328530328 140737028210640 140737028198504 140176326430010 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 40706 24424 10370 1052 0 16036 0 [pid=26635/tid=26640] ppid=26634 vsize=162824 CPUtime=1.6 /proc/26635/task/26640/stat : 26640 (aptitude) S 26634 26635 17863 34816 17863 4202560 6673 1709 0 0 156 3 0 1 20 0 2 0 29893219 166731776 24424 18446744073709551615 140176324222976 140176328530328 140737028210640 140176211900416 140176292967124 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.57 Current children cumulated vsize (KiB) 162824 [startup+4.7004 s] /proc/loadavg: 1.02 1.07 1.07 2/57 26640 /proc/meminfo: memFree=777048/1022884 swapFree=0/0 [pid=26635] ppid=26634 vsize=0 CPUtime=4.67 /proc/26635/stat : 26635 (aptitude) R 26634 26635 17863 34816 17863 4202500 27579 1709 0 0 453 13 0 1 20 0 1 0 29893142 0 0 18446744073709551615 0 0 0 0 0 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26635/statm: 0 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.67 Current children cumulated vsize (KiB) 0 Child status: 0 Real time (s): 4.7009 CPU time (s): 4.68429 CPU user time (s): 4.53628 CPU system time (s): 0.148009 CPU usage (%): 99.6468 Max. virtual memory (cumulated for all children) (KiB): 162824 getrusage(RUSAGE_CHILDREN,...) data: user time used= 4.53628 system time used= 0.148009 maximum resident set size= 97712 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 29288 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= 127 runsolver used 0.004 second user time and 0.024001 second system time The end