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/124.runsolver.aptitude aptitude -s -y --without-recommends remove tk8.4-dev khangman tidy libart-2.0-2 python 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.98 0.83 0.43 2/55 20512 /proc/meminfo: memFree=241996/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=3152 CPUtime=0 /proc/20512/stat : 20512 (runsolver) R 20511 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28217517 3227648 32 18446744073709551615 134512640 134586868 4288315168 4288313216 4151878704 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.170717 s] /proc/loadavg: 0.98 0.83 0.43 2/55 20512 /proc/meminfo: memFree=241996/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=86100 CPUtime=0.16 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 7108 1709 0 0 14 1 0 1 20 0 1 0 28217517 88166400 6899 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790073112 140597492352227 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 21525 6899 4712 1052 0 2116 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 86100 [startup+0.200304 s] /proc/loadavg: 0.98 0.83 0.43 2/55 20512 /proc/meminfo: memFree=241996/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=87812 CPUtime=0.2 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 7776 1709 0 0 17 2 0 1 20 0 1 0 28217517 89919488 7205 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790071512 140597535255700 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 21953 7205 4721 1052 0 2544 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 87812 [startup+0.300277 s] /proc/loadavg: 0.98 0.83 0.43 2/55 20512 /proc/meminfo: memFree=241996/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=90632 CPUtime=0.29 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 8629 1709 0 0 26 2 0 1 20 0 1 0 28217517 92807168 7673 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790071432 140597491740183 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 22658 7673 4725 1052 0 3249 0 Current children cumulated CPU time (s) 0.29 Current children cumulated vsize (KiB) 90632 [startup+0.700218 s] /proc/loadavg: 0.98 0.83 0.43 2/55 20512 /proc/meminfo: memFree=241996/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=100488 CPUtime=0.69 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 10073 1709 0 0 66 2 0 1 20 0 2 0 28217517 102899712 8702 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790072296 140597491710279 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 25122 8702 4977 1052 0 5713 0 Current children cumulated CPU time (s) 0.69 Current children cumulated vsize (KiB) 100488 [startup+1.50025 s] /proc/loadavg: 0.98 0.83 0.43 2/57 20517 /proc/meminfo: memFree=226604/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=113008 CPUtime=1.48 /proc/20512/stat : 20512 (aptitude) S 20511 20512 17863 34816 17863 4202496 13295 1709 0 0 144 3 0 1 20 0 2 0 28217517 115720192 11923 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790073760 140597502944843 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 28252 11923 5066 1052 0 8843 0 [pid=20512/tid=20517] ppid=20511 vsize=113008 CPUtime=0.37 /proc/20512/task/20517/stat : 20517 (aptitude) R 20511 20512 17863 34816 17863 4202560 3129 1709 0 0 35 1 0 1 20 0 2 0 28217547 115720192 11923 18446744073709551615 140597534199808 140597538507160 140736790082000 140597450772280 140597536896113 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) 113008 Solver just ended. Dumping a history of the last processes samples [startup+1.60034 s] /proc/loadavg: 0.98 0.83 0.43 2/57 20517 /proc/meminfo: memFree=226604/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=115696 CPUtime=1.58 /proc/20512/stat : 20512 (aptitude) S 20511 20512 17863 34816 17863 4202496 13963 1709 0 0 154 3 0 1 20 0 2 0 28217517 118472704 12591 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790073760 140597502944843 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 28924 12591 5066 1052 0 9515 0 [pid=20512/tid=20517] ppid=20511 vsize=115696 CPUtime=0.47 /proc/20512/task/20517/stat : 20517 (aptitude) R 20511 20512 17863 34816 17863 4202560 3797 1709 0 0 45 1 0 1 20 0 2 0 28217547 118472704 12591 18446744073709551615 140597534199808 140597538507160 140736790082000 140597450772280 140597536784638 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.58 Current children cumulated vsize (KiB) 115696 [startup+2.00025 s] /proc/loadavg: 0.98 0.83 0.43 2/57 20517 /proc/meminfo: memFree=226604/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=122164 CPUtime=1.98 /proc/20512/stat : 20512 (aptitude) S 20511 20512 17863 34816 17863 4202496 15591 1709 0 0 193 4 0 1 20 0 2 0 28217517 125095936 14219 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790073760 140597502944843 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 30541 14219 5070 1052 0 11132 0 [pid=20512/tid=20517] ppid=20511 vsize=122164 CPUtime=0.87 /proc/20512/task/20517/stat : 20517 (aptitude) R 20511 20512 17863 34816 17863 4202560 5424 1709 0 0 84 2 0 1 20 0 2 0 28217547 125095936 14219 18446744073709551615 140597534199808 140597538507160 140736790082000 140597450769944 140597536713161 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.98 Current children cumulated vsize (KiB) 122164 [startup+2.20025 s] /proc/loadavg: 0.98 0.83 0.43 2/57 20517 /proc/meminfo: memFree=202920/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=124308 CPUtime=2.18 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 16164 1709 0 0 213 4 0 1 20 0 2 0 28217517 127291392 14790 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790072088 140597491691179 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 31077 14790 5120 1052 0 11661 0 [pid=20512/tid=20517] ppid=20511 vsize=124308 CPUtime=1 /proc/20512/task/20517/stat : 20517 (aptitude) S 20511 20512 17863 34816 17863 4202560 5822 1709 0 0 97 2 0 1 20 0 2 0 28217547 127291392 14790 18446744073709551615 140597534199808 140597538507160 140736790082000 140597450778624 140597502943956 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.18 Current children cumulated vsize (KiB) 124308 [startup+2.30321 s] /proc/loadavg: 0.98 0.83 0.43 2/57 20517 /proc/meminfo: memFree=202920/1022884 swapFree=0/0 [pid=20512] ppid=20511 vsize=124308 CPUtime=2.29 /proc/20512/stat : 20512 (aptitude) R 20511 20512 17863 34816 17863 4202496 16172 1709 0 0 223 5 0 1 20 0 2 0 28217517 127291392 14797 18446744073709551615 140597534199808 140597538507160 140736790082000 140736790065400 140597529092469 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20512/statm: 31077 14797 5127 1052 0 11661 0 [pid=20512/tid=20517] ppid=20511 vsize=124308 CPUtime=1 /proc/20512/task/20517/stat : 20517 (aptitude) S 20511 20512 17863 34816 17863 4202560 5822 1709 0 0 97 2 0 1 20 0 2 0 28217547 127291392 14797 18446744073709551615 140597534199808 140597538507160 140736790082000 140597450778624 140597502943956 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.29 Current children cumulated vsize (KiB) 124308 Child status: 0 Real time (s): 2.32324 CPU time (s): 2.31614 CPU user time (s): 2.23614 CPU system time (s): 0.080005 CPU usage (%): 99.6947 Max. virtual memory (cumulated for all children) (KiB): 124308 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.23614 system time used= 0.080005 maximum resident set size= 59204 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 17949 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= 57 involuntary context switches= 90 runsolver used 0.004 second user time and 0.012 second system time The end