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/logs/146.runsolver.aptitude aptitude -s -y --without-recommends remove libgdbm3 kturtle kfilereplace libkpimexchange1 whiptail 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: 0.99 0.86 0.46 2/55 20666 /proc/meminfo: memFree=240816/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=3152 CPUtime=0 /proc/20666/stat : 20666 (runsolver) R 20665 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28222457 3227648 33 18446744073709551615 134512640 134586868 4290226464 4290224512 4151968816 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.176651 s] /proc/loadavg: 0.99 0.86 0.46 2/55 20666 /proc/meminfo: memFree=240816/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=86100 CPUtime=0.16 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 7108 1709 0 0 14 2 0 0 20 0 1 0 28222457 88166400 6899 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516935608 139635023813868 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 21525 6899 4712 1052 0 2116 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 86100 [startup+0.200295 s] /proc/loadavg: 0.99 0.86 0.46 2/55 20666 /proc/meminfo: memFree=240816/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=87812 CPUtime=0.19 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 7806 1709 0 0 17 2 0 0 20 0 1 0 28222457 89919488 7235 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516934008 139635030015124 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 21953 7235 4721 1052 0 2544 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 87812 [startup+0.300285 s] /proc/loadavg: 0.99 0.86 0.46 2/55 20666 /proc/meminfo: memFree=240816/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=95900 CPUtime=0.29 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 8837 1709 0 0 26 3 0 0 20 0 2 0 28222457 98201600 7471 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516937968 139634986469753 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 23975 7471 4875 1052 0 4566 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 95900 [startup+0.700207 s] /proc/loadavg: 0.99 0.86 0.46 2/55 20666 /proc/meminfo: memFree=240816/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=100468 CPUtime=0.68 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 10066 1709 0 0 64 4 0 0 20 0 2 0 28222457 102879232 8695 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516934960 139635023783033 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 25117 8695 4978 1052 0 5708 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 100468 [startup+1.50025 s] /proc/loadavg: 0.99 0.86 0.46 2/57 20671 /proc/meminfo: memFree=225184/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=113896 CPUtime=1.47 /proc/20666/stat : 20666 (aptitude) S 20665 20666 17863 34816 17863 4202496 13506 1709 0 0 141 6 0 0 20 0 2 0 28222457 116629504 12134 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516936256 139634997704267 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 28474 12134 5065 1052 0 9065 0 [pid=20666/tid=20671] ppid=20665 vsize=113896 CPUtime=0.4 /proc/20666/task/20671/stat : 20671 (aptitude) R 20665 20666 17863 34816 17863 4202560 3348 1709 0 0 38 2 0 0 20 0 2 0 28222487 116629504 12134 18446744073709551615 139635028959232 139635033266584 140736516944496 139634945528776 139635031471826 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.47 Current children cumulated vsize (KiB) 113896 Solver just ended. Dumping a history of the last processes samples [startup+1.60034 s] /proc/loadavg: 0.99 0.86 0.46 2/57 20671 /proc/meminfo: memFree=225184/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=117064 CPUtime=1.57 /proc/20666/stat : 20666 (aptitude) S 20665 20666 17863 34816 17863 4202496 14302 1709 0 0 151 6 0 0 20 0 2 0 28222457 119873536 12930 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516936256 139634997704267 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 29266 12930 5066 1052 0 9857 0 [pid=20666/tid=20671] ppid=20665 vsize=117064 CPUtime=0.5 /proc/20666/task/20671/stat : 20671 (aptitude) R 20665 20666 17863 34816 17863 4202560 4143 1709 0 0 48 2 0 0 20 0 2 0 28222487 119873536 12930 18446744073709551615 139635028959232 139635033266584 140736516944496 139634945529016 139635031529888 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.57 Current children cumulated vsize (KiB) 117064 [startup+2.40024 s] /proc/loadavg: 0.99 0.86 0.46 2/57 20671 /proc/meminfo: memFree=201376/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=127764 CPUtime=2.37 /proc/20666/stat : 20666 (aptitude) S 20665 20666 17863 34816 17863 4202496 17007 1709 0 0 229 8 0 0 20 0 2 0 28222457 130830336 15635 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516936256 139634997704267 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 31941 15635 5070 1052 0 12532 0 [pid=20666/tid=20671] ppid=20665 vsize=127764 CPUtime=1.3 /proc/20666/task/20671/stat : 20671 (aptitude) R 20665 20666 17863 34816 17863 4202560 6848 1709 0 0 126 4 0 0 20 0 2 0 28222487 130830336 15635 18446744073709551615 139635028959232 139635033266584 140736516944496 139634945529416 139635031702059 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.37 Current children cumulated vsize (KiB) 127764 [startup+2.60026 s] /proc/loadavg: 0.99 0.86 0.46 2/57 20671 /proc/meminfo: memFree=201376/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=128848 CPUtime=2.57 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 17330 1709 0 0 249 8 0 0 20 0 2 0 28222457 131940352 15956 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516934584 139635023800176 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 32212 15956 5120 1052 0 12796 0 [pid=20666/tid=20671] ppid=20665 vsize=128848 CPUtime=1.36 /proc/20666/task/20671/stat : 20671 (aptitude) S 20665 20666 17863 34816 17863 4202560 6944 1709 0 0 132 4 0 0 20 0 2 0 28222487 131940352 15956 18446744073709551615 139635028959232 139635033266584 140736516944496 139634945538048 139634997703380 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.57 Current children cumulated vsize (KiB) 128848 [startup+2.70049 s] /proc/loadavg: 0.99 0.86 0.46 2/57 20671 /proc/meminfo: memFree=201376/1022884 swapFree=0/0 [pid=20666] ppid=20665 vsize=114632 CPUtime=2.68 /proc/20666/stat : 20666 (aptitude) R 20665 20666 17863 34816 17863 4202496 17342 1709 0 0 258 10 0 0 20 0 1 0 28222457 117383168 9349 18446744073709551615 139635028959232 139635033266584 140736516944496 140736516941608 139634986840343 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20666/statm: 28658 9349 2059 1052 0 9242 0 Current children cumulated CPU time (s) 2.68 Current children cumulated vsize (KiB) 114632 Child status: 0 Real time (s): 2.71058 CPU time (s): 2.70017 CPU user time (s): 2.59216 CPU system time (s): 0.108006 CPU usage (%): 99.616 Max. virtual memory (cumulated for all children) (KiB): 128848 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.59216 system time used= 0.108006 maximum resident set size= 63824 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 19115 page faults= 0 swaps= 0 block input operations= 0 block output operations= 64 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 58 involuntary context switches= 97 runsolver used 0.004 second user time and 0.012 second system time The end