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/141.runsolver.aptitude aptitude -s -y --without-recommends remove gsfonts nautilus-cd-burner telnet ppp libarts1-audiofile 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.97 1.05 1.02 2/55 24559 /proc/meminfo: memFree=864520/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=54860 CPUtime=0 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 590 0 0 0 0 0 0 0 20 0 1 0 29235634 56176640 490 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075609176 139680024166896 0 0 0 0 0 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 13715 490 402 1052 0 73 0 [startup+0.175984 s] /proc/loadavg: 0.97 1.05 1.02 2/55 24559 /proc/meminfo: memFree=864520/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=100508 CPUtime=0.16 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 10598 1708 0 0 13 3 0 0 20 0 1 0 29235634 102920192 10399 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601232 139680021198614 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 25127 10399 9225 1052 0 1122 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 100508 [startup+0.200279 s] /proc/loadavg: 0.97 1.05 1.02 2/55 24559 /proc/meminfo: memFree=864520/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=100508 CPUtime=0.19 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 10605 1708 0 0 16 3 0 0 20 0 1 0 29235634 102920192 10406 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601232 139680021757872 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 25127 10406 9232 1052 0 1122 0 Current children cumulated CPU time (s) 0.19 Current children cumulated vsize (KiB) 100508 [startup+0.300334 s] /proc/loadavg: 0.97 1.05 1.02 2/55 24559 /proc/meminfo: memFree=864520/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=111108 CPUtime=0.28 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 12644 1708 0 0 24 4 0 0 20 0 1 0 29235634 113774592 12437 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601560 139680028489299 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 27777 12437 9302 1052 0 3772 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 111108 [startup+0.700206 s] /proc/loadavg: 0.97 1.05 1.02 2/55 24559 /proc/meminfo: memFree=864520/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=123288 CPUtime=0.68 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 17024 1708 0 0 61 7 0 0 20 0 2 0 29235634 126246912 14298 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075603592 139679984575025 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 30822 14298 9478 1052 0 6817 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 123288 [startup+1.50024 s] /proc/loadavg: 0.97 1.05 1.02 2/57 24564 /proc/meminfo: memFree=836488/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=131940 CPUtime=1.48 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 19296 1708 0 0 140 8 0 0 20 0 2 0 29235634 135106560 16566 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075597080 139680027986277 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 32985 16566 9568 1052 0 8980 0 [pid=24559/tid=24564] ppid=24558 vsize=131940 CPUtime=0 /proc/24559/task/24564/stat : 24564 (aptitude) S 24558 24559 17863 34816 17863 4202560 4 1708 0 0 0 0 0 0 20 0 2 0 29235699 135106560 16566 18446744073709551615 139680026357760 139680030665112 140734075609968 139679917185024 139679995101908 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) 131940 [startup+3.10024 s] /proc/loadavg: 0.97 1.05 1.02 2/57 24564 /proc/meminfo: memFree=835868/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=146132 CPUtime=3.07 /proc/24559/stat : 24559 (aptitude) S 24558 24559 17863 34816 17863 4202496 22927 1708 0 0 298 9 0 0 20 0 2 0 29235634 149639168 20196 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601728 139679995102795 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 36533 20196 9662 1052 0 12528 0 [pid=24559/tid=24564] ppid=24558 vsize=146132 CPUtime=0.55 /proc/24559/task/24564/stat : 24564 (aptitude) R 24558 24559 17863 34816 17863 4202560 3489 1708 0 0 54 1 0 0 20 0 2 0 29235699 149639168 20196 18446744073709551615 139680026357760 139680030665112 140734075609968 139679917179048 139680029065731 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) 146132 Solver just ended. Dumping a history of the last processes samples [startup+3.20037 s] /proc/loadavg: 0.97 1.05 1.02 2/57 24564 /proc/meminfo: memFree=835868/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=147320 CPUtime=3.17 /proc/24559/stat : 24559 (aptitude) S 24558 24559 17863 34816 17863 4202496 23232 1708 0 0 308 9 0 0 20 0 2 0 29235634 150855680 20501 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601728 139679995102795 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 36830 20501 9662 1052 0 12825 0 [pid=24559/tid=24564] ppid=24558 vsize=147320 CPUtime=0.65 /proc/24559/task/24564/stat : 24564 (aptitude) R 24558 24559 17863 34816 17863 4202560 3794 1708 0 0 64 1 0 0 20 0 2 0 29235699 150855680 20501 18446744073709551615 139680026357760 139680030665112 140734075609968 139679917177928 139679983868231 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.17 Current children cumulated vsize (KiB) 147320 [startup+3.60024 s] /proc/loadavg: 0.97 1.05 1.02 2/57 24564 /proc/meminfo: memFree=819748/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=154316 CPUtime=3.57 /proc/24559/stat : 24559 (aptitude) S 24558 24559 17863 34816 17863 4202496 24977 1708 0 0 347 10 0 0 20 0 2 0 29235634 158019584 22246 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075601728 139679995102795 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 38579 22246 9663 1052 0 14574 0 [pid=24559/tid=24564] ppid=24558 vsize=154316 CPUtime=1.04 /proc/24559/task/24564/stat : 24564 (aptitude) R 24558 24559 17863 34816 17863 4202560 5539 1708 0 0 102 2 0 0 20 0 2 0 29235699 158019584 22246 18446744073709551615 139680026357760 139680030665112 140734075609968 139679917175912 139680027186736 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 3.57 Current children cumulated vsize (KiB) 154316 [startup+4.00049 s] /proc/loadavg: 0.97 1.05 1.02 2/57 24564 /proc/meminfo: memFree=819748/1022884 swapFree=0/0 [pid=24559] ppid=24558 vsize=116196 CPUtime=3.96 /proc/24559/stat : 24559 (aptitude) R 24558 24559 17863 34816 17863 4202496 25325 1708 0 0 385 11 0 0 20 0 1 0 29235634 118984704 12808 18446744073709551615 139680026357760 139680030665112 140734075609968 140734075607048 139679984238871 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/24559/statm: 29049 12808 1466 1052 0 13305 0 Current children cumulated CPU time (s) 3.96 Current children cumulated vsize (KiB) 116196 Child status: 0 Real time (s): 4.01349 CPU time (s): 4.00025 CPU user time (s): 3.87224 CPU system time (s): 0.128008 CPU usage (%): 99.67 Max. virtual memory (cumulated for all children) (KiB): 155400 getrusage(RUSAGE_CHILDREN,...) data: user time used= 3.87224 system time used= 0.128008 maximum resident set size= 90364 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 27097 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= 56 involuntary context switches= 113 runsolver used 0.008 second user time and 0.016001 second system time The end