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/125.runsolver.aptitude aptitude -s -y --without-recommends remove libtunepimp-bin kdegames-card-data gnupg2 librsvg2-dev python2.3 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 s] /proc/loadavg: 1.04 1.05 1.07 2/55 26756 /proc/meminfo: memFree=832516/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=54860 CPUtime=0 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 586 0 0 0 0 0 0 0 20 0 1 0 29919046 56176640 485 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563565688 139644680409761 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 13715 485 397 1052 0 73 0 [startup+0.179365 s] /proc/loadavg: 1.04 1.05 1.07 2/55 26756 /proc/meminfo: memFree=832516/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=103288 CPUtime=0.16 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 11295 1710 0 0 15 1 0 0 20 0 1 0 29919046 105766912 11096 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563557744 139644677449066 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 25822 11096 9884 1052 0 1159 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 103288 [startup+0.200288 s] /proc/loadavg: 1.04 1.05 1.07 2/55 26756 /proc/meminfo: memFree=832516/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=103288 CPUtime=0.18 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 11299 1710 0 0 17 1 0 0 20 0 1 0 29919046 105766912 11100 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563557744 139644678008824 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 25822 11100 9888 1052 0 1159 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 103288 [startup+0.300271 s] /proc/loadavg: 1.04 1.05 1.07 2/55 26756 /proc/meminfo: memFree=832516/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=103288 CPUtime=0.28 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 11308 1710 0 0 27 1 0 0 20 0 1 0 29919046 105766912 11109 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563557744 139644677449175 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 25822 11109 9897 1052 0 1159 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 103288 [startup+0.700203 s] /proc/loadavg: 1.04 1.05 1.07 2/55 26756 /proc/meminfo: memFree=832516/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=123068 CPUtime=0.68 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 17688 1710 0 0 63 5 0 0 20 0 1 0 29919046 126021632 15965 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563555912 139644677509232 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 30767 15965 9976 1052 0 6104 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 123068 [startup+1.50024 s] /proc/loadavg: 1.04 1.05 1.07 2/57 26761 /proc/meminfo: memFree=803492/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=135856 CPUtime=1.48 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 20629 1710 0 0 142 6 0 0 20 0 2 0 29919046 139116544 17546 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563553592 139644683198064 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 33964 17546 10227 1052 0 9301 0 [pid=26756/tid=26761] ppid=26755 vsize=135856 CPUtime=0 /proc/26756/task/26761/stat : 26761 (aptitude) S 26755 26756 17863 34816 17863 4202560 4 1710 0 0 0 0 0 0 20 0 2 0 29919124 139116544 17546 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570286080 139644651352788 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.04 1.05 1.07 2/57 26761 /proc/meminfo: memFree=802500/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=151700 CPUtime=3.07 /proc/26756/stat : 26756 (aptitude) S 26755 26756 17863 34816 17863 4202496 24674 1710 0 0 300 7 0 0 20 0 2 0 29919046 155340800 21589 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563558240 139644651353675 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 37925 21589 10317 1052 0 13262 0 [pid=26756/tid=26761] ppid=26755 vsize=151700 CPUtime=0.27 /proc/26756/task/26761/stat : 26761 (aptitude) R 26755 26756 17863 34816 17863 4202560 3885 1710 0 0 26 1 0 0 20 0 2 0 29919124 155340800 21589 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570279608 139644685382658 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) 151700 Solver just ended. Dumping a history of the last processes samples [startup+3.20031 s] /proc/loadavg: 1.04 1.05 1.07 2/57 26761 /proc/meminfo: memFree=802500/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=156188 CPUtime=3.18 /proc/26756/stat : 26756 (aptitude) S 26755 26756 17863 34816 17863 4202496 25819 1710 0 0 310 8 0 0 20 0 2 0 29919046 159936512 22734 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563558240 139644651353675 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 39047 22734 10317 1052 0 14384 0 [pid=26756/tid=26761] ppid=26755 vsize=156188 CPUtime=0.37 /proc/26756/task/26761/stat : 26761 (aptitude) R 26755 26756 17863 34816 17863 4202560 5030 1710 0 0 36 1 0 0 20 0 2 0 29919124 159936512 22734 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570276968 139644640119161 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) 156188 [startup+4.80024 s] /proc/loadavg: 1.04 1.05 1.06 2/57 26761 /proc/meminfo: memFree=739880/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=204544 CPUtime=4.76 /proc/26756/stat : 26756 (aptitude) S 26755 26756 17863 34816 17863 4202496 37896 1710 0 0 463 13 0 0 20 0 2 0 29919046 209453056 34811 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563558240 139644651353675 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 51136 34811 10321 1052 0 26473 0 [pid=26756/tid=26761] ppid=26755 vsize=204544 CPUtime=1.96 /proc/26756/task/26761/stat : 26761 (aptitude) R 26755 26756 17863 34816 17863 4202560 17106 1710 0 0 189 7 0 0 20 0 2 0 29919124 209453056 34811 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570277752 139644684819558 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.76 Current children cumulated vsize (KiB) 204544 [startup+5.60325 s] /proc/loadavg: 1.04 1.05 1.06 2/57 26761 /proc/meminfo: memFree=728348/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=210484 CPUtime=5.56 /proc/26756/stat : 26756 (aptitude) S 26755 26756 17863 34816 17863 4202496 39395 1710 0 0 542 14 0 0 20 0 2 0 29919046 215535616 36310 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563558240 139644651353675 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 52621 36310 10321 1052 0 27958 0 [pid=26756/tid=26761] ppid=26755 vsize=210484 CPUtime=2.75 /proc/26756/task/26761/stat : 26761 (aptitude) R 26755 26756 17863 34816 17863 4202560 18605 1710 0 0 268 7 0 0 20 0 2 0 29919124 215535616 36310 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570276968 139644678009394 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.56 Current children cumulated vsize (KiB) 210484 [startup+6.00025 s] /proc/loadavg: 1.04 1.05 1.06 2/57 26761 /proc/meminfo: memFree=728348/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=211708 CPUtime=5.96 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 39755 1710 0 0 582 14 0 0 20 0 2 0 29919046 216788992 36668 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563554344 139644684815100 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 52927 36668 10370 1052 0 28257 0 [pid=26756/tid=26761] ppid=26755 vsize=211708 CPUtime=2.81 /proc/26756/task/26761/stat : 26761 (aptitude) S 26755 26756 17863 34816 17863 4202560 18809 1710 0 0 274 7 0 0 20 0 2 0 29919124 216788992 36668 18446744073709551615 139644682608640 139644686915992 140733563566480 139644570286080 139644651352788 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.96 Current children cumulated vsize (KiB) 211708 [startup+6.10049 s] /proc/loadavg: 1.04 1.05 1.06 2/57 26761 /proc/meminfo: memFree=728348/1022884 swapFree=0/0 [pid=26756] ppid=26755 vsize=211708 CPUtime=6.05 /proc/26756/stat : 26756 (aptitude) R 26755 26756 17863 34816 17863 4202496 39768 1710 0 0 591 14 0 0 20 0 1 0 29919046 216788992 32583 18446744073709551615 139644682608640 139644686915992 140733563566480 140733563563528 139644640474602 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/26756/statm: 52927 32583 10381 1052 0 28257 0 Current children cumulated CPU time (s) 6.05 Current children cumulated vsize (KiB) 211708 Child status: 0 Real time (s): 6.13346 CPU time (s): 6.10838 CPU user time (s): 5.92437 CPU system time (s): 0.184011 CPU usage (%): 99.591 Max. virtual memory (cumulated for all children) (KiB): 211708 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.92437 system time used= 0.184011 maximum resident set size= 146716 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 41541 page faults= 0 swaps= 0 block input operations= 0 block output operations= 32 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 61 involuntary context switches= 151 runsolver used 0.012 second user time and 0.020001 second system time The end