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/110.runsolver.aptitude aptitude -s -y --without-recommends remove libvorbis-ocaml sed libgnomeprintui2.2-0 quanta-data libsvn0 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.34 1.11 1.05 2/55 24341 /proc/meminfo: memFree=966208/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=3152 CPUtime=0 /proc/24341/stat : 24341 (runsolver) R 24340 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 29220288 3227648 32 18446744073709551615 134512640 134586868 4292101792 4292099840 4151604272 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/24341/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.151928 s] /proc/loadavg: 1.34 1.11 1.05 2/55 24341 /proc/meminfo: memFree=966208/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=28716 CPUtime=0 /proc/24341/stat : 24341 (aptitude) D 24340 24341 17863 34816 17863 4202496 149 0 18 0 0 0 0 0 20 0 1 0 29220288 29405184 78 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022547880 140243302872611 0 0 0 0 0 0 0 17 0 0 0 14 0 0 /proc/24341/statm: 7179 78 50 1052 0 33 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 28716 [startup+0.200361 s] /proc/loadavg: 1.34 1.11 1.05 2/55 24341 /proc/meminfo: memFree=966208/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=36128 CPUtime=0 /proc/24341/stat : 24341 (aptitude) D 24340 24341 17863 34816 17863 4202496 162 0 21 0 0 0 0 0 20 0 1 0 29220288 36995072 92 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022547688 140243302872454 0 0 0 0 0 0 0 17 0 0 0 17 0 0 /proc/24341/statm: 9032 92 58 1052 0 39 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 36128 [startup+0.300391 s] /proc/loadavg: 1.34 1.11 1.05 2/55 24341 /proc/meminfo: memFree=966208/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=54860 CPUtime=0 /proc/24341/stat : 24341 (aptitude) R 24340 24341 17863 34816 17863 4202496 432 0 35 0 0 0 0 0 20 0 1 0 29220288 56176640 366 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022550472 140243302822888 0 0 0 0 0 0 0 17 0 0 0 28 0 0 /proc/24341/statm: 13715 366 284 1052 0 73 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 54860 [startup+0.70033 s] /proc/loadavg: 1.34 1.11 1.05 2/55 24341 /proc/meminfo: memFree=966208/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=63384 CPUtime=0 /proc/24341/stat : 24341 (aptitude) S 24340 24341 17863 34816 17863 4202496 1179 0 64 0 0 0 0 0 20 0 1 0 29220288 64905216 1108 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022542312 140243262858992 0 134217728 4096 0 0 0 0 17 0 0 0 65 0 0 /proc/24341/statm: 15846 1108 929 1052 0 109 0 Current children cumulated CPU time (s) 0 Current children cumulated vsize (KiB) 63384 [startup+1.50033 s] /proc/loadavg: 1.31 1.11 1.04 1/56 24345 /proc/meminfo: memFree=955164/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=100508 CPUtime=0.02 /proc/24341/stat : 24341 (aptitude) D 24340 24341 17863 34816 17863 4202496 3770 1704 192 4 1 1 0 0 20 0 1 0 29220288 102920192 3763 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022542736 140243300063130 0 134217728 4096 0 0 0 0 17 0 0 0 141 0 0 /proc/24341/statm: 25127 3763 2588 1052 0 1122 0 Current children cumulated CPU time (s) 0.02 Current children cumulated vsize (KiB) 100508 [startup+3.10031 s] /proc/loadavg: 1.31 1.11 1.04 2/56 24345 /proc/meminfo: memFree=953304/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=119772 CPUtime=0.6 /proc/24341/stat : 24341 (aptitude) R 24340 24341 17863 34816 17863 4202496 16209 1704 297 4 48 12 0 0 20 0 1 0 29220288 122646528 14784 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022540696 140243273769328 0 134217728 4096 0 0 0 0 17 0 0 0 243 0 0 /proc/24341/statm: 29943 14784 9317 1052 0 5938 0 Current children cumulated CPU time (s) 0.6 Current children cumulated vsize (KiB) 119772 heavy processes: [startup+6.30038 s] /proc/loadavg: 1.31 1.11 1.04 2/57 24346 /proc/meminfo: memFree=831900/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=143096 CPUtime=3.47 /proc/24341/stat : 24341 (aptitude) D 24340 24341 17863 34816 17863 4202496 21885 1704 306 4 332 15 0 0 20 0 2 0 29220288 146530304 19457 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022539280 140243262187683 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 272 0 0 /proc/24341/statm: 35774 19457 9678 1052 0 11762 0 [pid=24341/tid=24346] ppid=24340 vsize=143096 CPUtime=0.9 /proc/24341/task/24346/stat : 24346 (aptitude) S 24340 24341 17863 34816 17863 4202560 2731 1704 1 4 90 0 0 0 20 0 2 0 29220629 146530304 19457 18446744073709551615 140243305013248 140243309320600 140736022551264 140243195840512 140243273757396 0 134217728 4096 0 0 0 0 -1 0 0 0 1 0 0 Current children cumulated CPU time (s) 3.47 Current children cumulated vsize (KiB) 143096 heavy processes: Solver just ended. Dumping a history of the last processes samples [startup+6.4034 s] /proc/loadavg: 1.29 1.11 1.04 1/57 24346 /proc/meminfo: memFree=828552/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=143096 CPUtime=3.56 /proc/24341/stat : 24341 (aptitude) R 24340 24341 17863 34816 17863 4202496 21923 1704 311 4 341 15 0 0 20 0 2 0 29220288 146530304 19500 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022539128 140243307219623 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 274 0 0 /proc/24341/statm: 35774 19500 9711 1052 0 11762 0 [pid=24341/tid=24346] ppid=24340 vsize=143096 CPUtime=0.9 /proc/24341/task/24346/stat : 24346 (aptitude) S 24340 24341 17863 34816 17863 4202560 2731 1704 1 4 90 0 0 0 20 0 2 0 29220629 146530304 19500 18446744073709551615 140243305013248 140243309320600 140736022551264 140243195840512 140243273757396 0 134217728 4096 0 0 0 0 -1 0 0 0 1 0 0 Current children cumulated CPU time (s) 3.56 Current children cumulated vsize (KiB) 143096 [startup+6.50026 s] /proc/loadavg: 1.29 1.11 1.04 1/57 24346 /proc/meminfo: memFree=828552/1022884 swapFree=0/0 [pid=24341] ppid=24340 vsize=143096 CPUtime=3.65 /proc/24341/stat : 24341 (aptitude) R 24340 24341 17863 34816 17863 4202496 21923 1704 312 4 350 15 0 0 20 0 2 0 29220288 146530304 19501 18446744073709551615 140243305013248 140243309320600 140736022551264 140736022543336 140243306746805 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 274 0 0 /proc/24341/statm: 35774 19501 9712 1052 0 11762 0 [pid=24341/tid=24346] ppid=24340 vsize=143096 CPUtime=0.9 /proc/24341/task/24346/stat : 24346 (aptitude) S 24340 24341 17863 34816 17863 4202560 2731 1704 1 4 90 0 0 0 20 0 2 0 29220629 146530304 19501 18446744073709551615 140243305013248 140243309320600 140736022551264 140243195840512 140243273757396 0 134217728 4096 0 0 0 0 -1 0 0 0 1 0 0 Current children cumulated CPU time (s) 3.65 Current children cumulated vsize (KiB) 143096 Child status: 255 Real time (s): 6.57205 CPU time (s): 3.70023 CPU user time (s): 3.51622 CPU system time (s): 0.184011 CPU usage (%): 56.3025 Max. virtual memory (cumulated for all children) (KiB): 143096 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.51622 system time used= 0.184011 maximum resident set size= 78068 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 23691 page faults= 332 swaps= 0 block input operations= 199200 block output operations= 8 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 1962 involuntary context switches= 101 runsolver used 0.012 second user time and 0.032002 second system time The end