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/131.runsolver.aptitude aptitude -s -y --without-recommends remove ucf libnautilus-burn0 libjpeg-progs kpdf kgpg 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.84 0.44 2/55 20561 /proc/meminfo: memFree=241724/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=3152 CPUtime=0 /proc/20561/stat : 20561 (runsolver) R 20560 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28218940 3227648 33 18446744073709551615 134512640 134586868 4287047472 4287045520 4152042544 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 788 33 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.138874 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20561 /proc/meminfo: memFree=241724/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=80316 CPUtime=0.12 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 5658 1709 0 0 11 1 0 0 20 0 1 0 28218940 82243584 5448 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150657792 139847920105442 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 20079 5448 4706 1052 0 670 0 Current children cumulated CPU time (s) 0.12 Current children cumulated vsize (KiB) 80316 [startup+0.200276 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20561 /proc/meminfo: memFree=241724/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=87044 CPUtime=0.18 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 7499 1709 0 0 17 1 0 0 20 0 1 0 28218940 89133056 7121 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150657528 139847926307988 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 21761 7121 4721 1052 0 2352 0 Current children cumulated CPU time (s) 0.18 Current children cumulated vsize (KiB) 87044 [startup+0.300277 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20561 /proc/meminfo: memFree=241724/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=90632 CPUtime=0.28 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 8630 1709 0 0 25 3 0 0 20 0 1 0 28218940 92807168 7674 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150657448 139847920151336 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 22658 7674 4725 1052 0 3249 0 Current children cumulated CPU time (s) 0.28 Current children cumulated vsize (KiB) 90632 [startup+0.700217 s] /proc/loadavg: 0.98 0.84 0.44 2/55 20561 /proc/meminfo: memFree=241724/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=100468 CPUtime=0.68 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 10067 1709 0 0 64 4 0 0 20 0 2 0 28218940 102879232 8695 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150657272 139847882762376 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 25117 8695 4977 1052 0 5708 0 Current children cumulated CPU time (s) 0.68 Current children cumulated vsize (KiB) 100468 [startup+1.50024 s] /proc/loadavg: 0.98 0.84 0.44 2/57 20566 /proc/meminfo: memFree=226712/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=112840 CPUtime=1.47 /proc/20561/stat : 20561 (aptitude) S 20560 20561 17863 34816 17863 4202496 13276 1709 0 0 143 4 0 0 20 0 2 0 28218940 115548160 11903 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150659776 139847893997131 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 28210 11903 5065 1052 0 8801 0 [pid=20561/tid=20566] ppid=20560 vsize=112840 CPUtime=0.37 /proc/20561/task/20566/stat : 20566 (aptitude) R 20560 20561 17863 34816 17863 4202560 3117 1709 0 0 36 1 0 0 20 0 2 0 28218971 115548160 11903 18446744073709551615 139847925252096 139847929559448 140734150668016 139847841824440 139847927948357 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) 112840 Solver just ended. Dumping a history of the last processes samples [startup+1.60033 s] /proc/loadavg: 0.98 0.84 0.44 2/57 20566 /proc/meminfo: memFree=226712/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=116140 CPUtime=1.57 /proc/20561/stat : 20561 (aptitude) S 20560 20561 17863 34816 17863 4202496 14072 1709 0 0 153 4 0 0 20 0 2 0 28218940 118927360 12699 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150659776 139847893997131 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 29035 12699 5065 1052 0 9626 0 [pid=20561/tid=20566] ppid=20560 vsize=116140 CPUtime=0.47 /proc/20561/task/20566/stat : 20566 (aptitude) R 20560 20561 17863 34816 17863 4202560 3913 1709 0 0 46 1 0 0 20 0 2 0 28218971 118927360 12699 18446744073709551615 139847925252096 139847929559448 140734150668016 139847841824616 139847882762376 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) 116140 [startup+2.00024 s] /proc/loadavg: 0.98 0.84 0.44 2/57 20566 /proc/meminfo: memFree=226712/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=121952 CPUtime=1.97 /proc/20561/stat : 20561 (aptitude) S 20560 20561 17863 34816 17863 4202496 15536 1709 0 0 192 5 0 0 20 0 2 0 28218940 124878848 14163 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150659776 139847893997131 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 30488 14163 5070 1052 0 11079 0 [pid=20561/tid=20566] ppid=20560 vsize=121952 CPUtime=0.86 /proc/20561/task/20566/stat : 20566 (aptitude) R 20560 20561 17863 34816 17863 4202560 5376 1709 0 0 85 1 0 0 20 0 2 0 28218971 124878848 14163 18446744073709551615 139847925252096 139847929559448 140734150668016 139847841826408 139847927866533 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.97 Current children cumulated vsize (KiB) 121952 [startup+2.40025 s] /proc/loadavg: 0.98 0.84 0.44 2/57 20566 /proc/meminfo: memFree=203772/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=125676 CPUtime=2.38 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 16533 1709 0 0 232 6 0 0 20 0 2 0 28218940 128692224 15157 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150658152 139847882747728 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 31419 15157 5119 1052 0 12003 0 [pid=20561/tid=20566] ppid=20560 vsize=125676 CPUtime=1.22 /proc/20561/task/20566/stat : 20566 (aptitude) S 20560 20561 17863 34816 17863 4202560 6164 1709 0 0 120 2 0 0 20 0 2 0 28218971 128692224 15157 18446744073709551615 139847925252096 139847929559448 140734150668016 139847841830912 139847893996244 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.38 Current children cumulated vsize (KiB) 125676 [startup+2.50026 s] /proc/loadavg: 0.98 0.84 0.44 2/57 20566 /proc/meminfo: memFree=203772/1022884 swapFree=0/0 [pid=20561] ppid=20560 vsize=125676 CPUtime=2.48 /proc/20561/stat : 20561 (aptitude) R 20560 20561 17863 34816 17863 4202496 16534 1709 0 0 242 6 0 0 20 0 2 0 28218940 128692224 15158 18446744073709551615 139847925252096 139847929559448 140734150668016 140734150657848 139847920106728 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20561/statm: 31419 15158 5120 1052 0 12003 0 [pid=20561/tid=20566] ppid=20560 vsize=125676 CPUtime=1.22 /proc/20561/task/20566/stat : 20566 (aptitude) S 20560 20561 17863 34816 17863 4202560 6164 1709 0 0 120 2 0 0 20 0 2 0 28218971 128692224 15158 18446744073709551615 139847925252096 139847929559448 140734150668016 139847841830912 139847893996244 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.48 Current children cumulated vsize (KiB) 125676 Child status: 0 Real time (s): 2.58811 CPU time (s): 2.58016 CPU user time (s): 2.50016 CPU system time (s): 0.080005 CPU usage (%): 99.6928 Max. virtual memory (cumulated for all children) (KiB): 125676 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.50016 system time used= 0.080005 maximum resident set size= 60676 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 18318 page faults= 0 swaps= 0 block input operations= 0 block output operations= 56 messages sent= 0 messages received= 0 signals received= 0 voluntary context switches= 58 involuntary context switches= 91 runsolver used 0.008 second user time and 0.008 second system time The end