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/108.runsolver.aptitude aptitude -s -y --without-recommends remove kde-core ssh gnome-applets-data knewsticker-scripts imagemagick 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.92 0.80 0.40 2/56 20183 /proc/meminfo: memFree=242516/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=3152 CPUtime=0 /proc/20183/stat : 20183 (runsolver) R 20182 17863 17863 34816 17863 4202560 0 0 0 0 0 0 0 0 20 0 1 0 28213870 3227648 32 18446744073709551615 134512640 134586868 4287772064 4287770112 4151518256 0 0 0 24578 0 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 788 32 0 19 0 73 0 Current StackSize limit: 8192 KiB [startup+0.159167 s] /proc/loadavg: 0.92 0.80 0.40 2/56 20183 /proc/meminfo: memFree=242516/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=80316 CPUtime=0.14 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 5657 1707 0 0 11 2 1 0 20 0 1 0 28213870 82243584 5447 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212231600 140416147353280 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 20079 5447 4706 1052 0 670 0 Current children cumulated CPU time (s) 0.14 Current children cumulated vsize (KiB) 80316 [startup+0.200293 s] /proc/loadavg: 0.92 0.80 0.40 2/56 20183 /proc/meminfo: memFree=242516/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=86100 CPUtime=0.16 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 7108 1707 0 0 12 3 1 0 20 0 1 0 28213870 88166400 6898 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212233472 140416184460544 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 21525 6898 4711 1052 0 2116 0 Current children cumulated CPU time (s) 0.16 Current children cumulated vsize (KiB) 86100 [startup+0.300356 s] /proc/loadavg: 0.92 0.80 0.40 2/56 20183 /proc/meminfo: memFree=242516/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=89348 CPUtime=0.2 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 8317 1707 0 0 16 3 1 0 20 0 1 0 28213870 91492352 7361 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212231336 140416192710631 0 134217728 4096 0 0 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 22337 7361 4721 1052 0 2928 0 Current children cumulated CPU time (s) 0.2 Current children cumulated vsize (KiB) 89348 [startup+0.700196 s] /proc/loadavg: 0.92 0.80 0.40 2/56 20183 /proc/meminfo: memFree=242516/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=100336 CPUtime=0.39 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 9984 1707 0 0 34 4 1 0 20 0 2 0 28213870 102744064 8615 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212232888 140416191407613 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 25084 8615 4939 1052 0 5675 0 Current children cumulated CPU time (s) 0.39 Current children cumulated vsize (KiB) 100336 [startup+1.50027 s] /proc/loadavg: 0.92 0.80 0.40 3/57 20236 /proc/meminfo: memFree=227132/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=100468 CPUtime=0.74 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 10072 1707 0 0 69 4 1 0 20 0 2 0 28213870 102879232 8701 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212236072 140416191492994 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 25117 8701 4984 1052 0 5708 0 [pid=20183/tid=20203] ppid=20182 vsize=100468 CPUtime=0.01 /proc/20183/task/20203/stat : 20203 (aptitude) S 20182 20183 17863 34816 17863 4202560 4 1707 0 0 0 0 1 0 20 0 2 0 28213921 102879232 8701 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106350592 140416158515924 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 0.74 Current children cumulated vsize (KiB) 100468 [startup+3.10026 s] /proc/loadavg: 1.01 0.82 0.41 3/57 20291 /proc/meminfo: memFree=226752/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=172832 CPUtime=1.56 /proc/20183/stat : 20183 (aptitude) S 20182 20183 17863 34816 17863 4202496 11870 1707 0 0 150 5 1 0 20 0 2 0 28213870 176979968 10498 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212233584 140416158516811 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 43208 10498 5068 1052 0 23799 0 [pid=20183/tid=20203] ppid=20182 vsize=172832 CPUtime=0.41 /proc/20183/task/20203/stat : 20203 (aptitude) R 20182 20183 17863 34816 17863 4202560 1711 1707 0 0 40 0 1 0 20 0 2 0 28213921 176979968 10498 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106341080 140416147273375 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.56 Current children cumulated vsize (KiB) 172832 heavy processes: Solver just ended. Dumping a history of the last processes samples [startup+3.20335 s] /proc/loadavg: 1.01 0.82 0.41 2/58 20321 /proc/meminfo: memFree=219932/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=173624 CPUtime=1.66 /proc/20183/stat : 20183 (aptitude) S 20182 20183 17863 34816 17863 4202496 12069 1707 0 0 160 5 1 0 20 0 2 0 28213870 177790976 10697 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212233584 140416158516811 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 43406 10697 5069 1052 0 23997 0 [pid=20183/tid=20203] ppid=20182 vsize=173624 CPUtime=0.51 /proc/20183/task/20203/stat : 20203 (aptitude) R 20182 20183 17863 34816 17863 4202560 1910 1707 0 0 50 0 1 0 20 0 2 0 28213921 177790976 10697 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106340840 140416147282044 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 1.66 Current children cumulated vsize (KiB) 173624 [startup+3.60027 s] /proc/loadavg: 1.01 0.82 0.41 2/58 20321 /proc/meminfo: memFree=218816/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=176264 CPUtime=2.05 /proc/20183/stat : 20183 (aptitude) S 20182 20183 17863 34816 17863 4202496 12736 1707 0 0 198 6 1 0 20 0 2 0 28213870 180494336 11364 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212233584 140416158516811 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 44066 11364 5069 1052 0 24657 0 [pid=20183/tid=20203] ppid=20182 vsize=176264 CPUtime=0.9 /proc/20183/task/20203/stat : 20203 (aptitude) R 20182 20183 17863 34816 17863 4202560 2577 1707 0 0 88 1 1 0 20 0 2 0 28213921 180494336 11364 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106344504 140416147924304 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.05 Current children cumulated vsize (KiB) 176264 [startup+4.00026 s] /proc/loadavg: 1.01 0.82 0.41 2/58 20321 /proc/meminfo: memFree=218816/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=179168 CPUtime=2.45 /proc/20183/stat : 20183 (aptitude) S 20182 20183 17863 34816 17863 4202496 13457 1707 0 0 238 6 1 0 20 0 2 0 28213870 183468032 12085 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212233584 140416158516811 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 44792 12085 5070 1052 0 25383 0 [pid=20183/tid=20203] ppid=20182 vsize=179168 CPUtime=1.3 /proc/20183/task/20203/stat : 20203 (aptitude) R 20182 20183 17863 34816 17863 4202560 3298 1707 0 0 128 1 1 0 20 0 2 0 28213921 183468032 12085 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106344024 140416147271984 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.45 Current children cumulated vsize (KiB) 179168 [startup+4.10026 s] /proc/loadavg: 1.01 0.82 0.41 2/58 20321 /proc/meminfo: memFree=218816/1022884 swapFree=0/0 [pid=20183] ppid=20182 vsize=181176 CPUtime=2.55 /proc/20183/stat : 20183 (aptitude) R 20182 20183 17863 34816 17863 4202496 14024 1707 0 0 248 6 1 0 20 0 2 0 28213870 185524224 12650 18446744073709551615 140416189771776 140416194079128 140737212241824 140737212229688 140416184626142 0 134217728 4096 0 18446744073709551615 0 0 17 0 0 0 0 0 0 /proc/20183/statm: 45294 12650 5119 1052 0 25878 0 [pid=20183/tid=20203] ppid=20182 vsize=181176 CPUtime=1.36 /proc/20183/task/20203/stat : 20203 (aptitude) S 20182 20183 17863 34816 17863 4202560 3810 1707 0 0 134 1 1 0 20 0 2 0 28213921 185524224 12650 18446744073709551615 140416189771776 140416194079128 140737212241824 140416106350592 140416158515924 0 134217728 4096 0 0 0 0 -1 0 0 0 0 0 0 Current children cumulated CPU time (s) 2.55 Current children cumulated vsize (KiB) 181176 Child status: 0 Real time (s): 4.17581 CPU time (s): 2.63216 CPU user time (s): 2.55616 CPU system time (s): 0.076004 CPU usage (%): 63.0336 Max. virtual memory (cumulated for all children) (KiB): 181176 getrusage(RUSAGE_CHILDREN,...) data: user time used= 2.55616 system time used= 0.076004 maximum resident set size= 50868 integral shared memory size= 0 integral unshared data size= 0 integral unshared stack size= 0 page reclaims= 15870 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= 57 involuntary context switches= 512 runsolver used 0.004 second user time and 0.024001 second system time The end