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/147.runsolver.aptitude aptitude -s -y --without-recommends remove gcc-2.95 libgimpprint1 kde-devel kdesdk-kfile-plugins libxrender1 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: 0.98 1.04 1.02 2/55 24601 /proc/meminfo: memFree=864024/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=54860 CPUtime=0 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 588 0 0 0 0 0 0 0 20 0 1 0 29239236 56176640 487 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674360120 140102437442621 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 13715 487 399 1052 0 73 0 [startup+0.204331 s] /proc/loadavg: 0.98 1.04 1.02 2/55 24601 /proc/meminfo: memFree=864024/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=100508 CPUtime=0.19 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 10609 1710 0 0 17 2 0 0 20 0 1 0 29239236 102920192 10407 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674352176 140102397214640 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 25127 10407 9232 1052 0 1122 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 100508 [startup+0.300287 s] /proc/loadavg: 0.98 1.04 1.02 2/55 24601 /proc/meminfo: memFree=864024/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=111108 CPUtime=0.28 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 12609 1710 0 0 26 2 0 0 20 0 1 0 29239236 113774592 12399 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674352504 140102441764443 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 27777 12399 9302 1052 0 3772 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 111108 [startup+0.400271 s] /proc/loadavg: 0.98 1.04 1.02 2/55 24601 /proc/meminfo: memFree=864024/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=117476 CPUtime=0.38 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 15741 1710 0 0 35 3 0 0 20 0 1 0 29239236 120295424 14016 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674350424 140102442557169 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 29369 14016 9312 1052 0 5364 0 Current children cumulated CPU time (s) 0.38 Current children cumulated vsize (KiB) 117476 [startup+0.700207 s] /proc/loadavg: 0.98 1.04 1.02 2/55 24601 /proc/meminfo: memFree=864024/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=123288 CPUtime=0.68 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 17027 1710 0 0 64 4 0 0 20 0 2 0 29239236 126246912 14299 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674354536 140102441758245 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 30822 14299 9478 1052 0 6817 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 123288 [startup+1.50023 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=835992/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=131964 CPUtime=1.48 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 19304 1710 0 0 143 5 0 0 20 0 2 0 29239236 135131136 16572 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674349032 140102397143093 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 32991 16572 9567 1052 0 8986 0 [pid=24601/tid=24606] ppid=24600 vsize=131964 CPUtime=0 /proc/24601/task/24606/stat : 24606 (aptitude) S 24600 24601 17863 34816 17863 4202560 4 1710 0 0 0 0 0 0 20 0 2 0 29239301 135131136 16572 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330460160 140102408377044 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) 131964 [startup+3.10023 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=835372/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=153524 CPUtime=3.07 /proc/24601/stat : 24601 (aptitude) S 24600 24601 17863 34816 17863 4202496 24784 1710 0 0 301 6 0 0 20 0 2 0 29239236 157208576 22051 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674352672 140102408377931 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 38381 22051 9659 1052 0 14376 0 [pid=24601/tid=24606] ppid=24600 vsize=153524 CPUtime=0.53 /proc/24601/task/24606/stat : 24606 (aptitude) R 24600 24601 17863 34816 17863 4202560 5337 1710 0 0 52 1 0 0 20 0 2 0 29239301 157208576 22051 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330450744 140102442406941 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) 153524 Solver just ended. Dumping a history of the last processes samples [startup+3.30024 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=835372/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=159596 CPUtime=3.27 /proc/24601/stat : 24601 (aptitude) S 24600 24601 17863 34816 17863 4202496 26323 1710 0 0 320 7 0 0 20 0 2 0 29239236 163426304 23590 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674352672 140102408377931 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 39899 23590 9659 1052 0 15894 0 [pid=24601/tid=24606] ppid=24600 vsize=159596 CPUtime=0.72 /proc/24601/task/24606/stat : 24606 (aptitude) R 24600 24601 17863 34816 17863 4202560 6876 1710 0 0 70 2 0 0 20 0 2 0 29239301 163426304 23590 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330449448 140102442274661 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.27 Current children cumulated vsize (KiB) 159596 [startup+4.90024 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=762460/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=280804 CPUtime=4.87 /proc/24601/stat : 24601 (aptitude) S 24600 24601 17863 34816 17863 4202496 40242 1710 0 0 477 10 0 0 20 0 2 0 29239236 287543296 37509 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674352672 140102408377931 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 70201 37509 9660 1052 0 46196 0 [pid=24601/tid=24606] ppid=24600 vsize=280804 CPUtime=2.31 /proc/24601/task/24606/stat : 24606 (aptitude) R 24600 24601 17863 34816 17863 4202560 20794 1710 0 0 227 4 0 0 20 0 2 0 29239301 287543296 37509 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330453816 140102442279333 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 4.87 Current children cumulated vsize (KiB) 280804 [startup+5.70316 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=740016/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=292516 CPUtime=5.66 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 43352 1710 0 0 556 10 0 0 20 0 2 0 29239236 299536384 40577 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674351048 140102397143085 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 73129 40577 9711 1052 0 49117 0 [pid=24601/tid=24606] ppid=24600 vsize=292516 CPUtime=2.97 /proc/24601/task/24606/stat : 24606 (aptitude) S 24600 24601 17863 34816 17863 4202560 23691 1710 0 0 292 5 0 0 20 0 2 0 29239301 299536384 40577 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330460160 140102408377044 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 5.66 Current children cumulated vsize (KiB) 292516 [startup+6.10023 s] /proc/loadavg: 0.98 1.04 1.02 2/57 24606 /proc/meminfo: memFree=740016/1022884 swapFree=0/0 [pid=24601] ppid=24600 vsize=292588 CPUtime=6.05 /proc/24601/stat : 24601 (aptitude) R 24600 24601 17863 34816 17863 4202496 43466 1710 0 0 595 10 0 0 20 0 2 0 29239236 299610112 40597 18446744073709551615 140102439632896 140102443940248 140735674360912 140735674353016 140102397124178 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24601/statm: 73147 40597 9720 1052 0 49135 0 [pid=24601/tid=24606] ppid=24600 vsize=292588 CPUtime=2.97 /proc/24601/task/24606/stat : 24606 (aptitude) S 24600 24601 17863 34816 17863 4202560 23691 1710 0 0 292 5 0 0 20 0 2 0 29239301 299610112 40597 18446744073709551615 140102439632896 140102443940248 140735674360912 140102330460160 140102408377044 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 6.05 Current children cumulated vsize (KiB) 292588 Child status: 0 Real time (s): 6.16589 CPU time (s): 6.14438 CPU user time (s): 5.99637 CPU system time (s): 0.148009 CPU usage (%): 99.6511 Max. virtual memory (cumulated for all children) (KiB): 292588 getrusage(RUSAGE_CHILDREN,...) data: user time used= 5.99637 system time used= 0.148009 maximum resident set size= 162660 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 45244 page faults= 0 swaps= 0 block input operations= 0 block output operations= 80 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 62 involuntary context switches= 152 runsolver used 0.008 second user time and 0.024001 second system time The end