From mboxrd@z Thu Jan 1 00:00:00 1970 X-Msuck: nntp://news.gmane.io/gmane.emacs.gnus.general/74214 Path: news.gmane.org!not-for-mail From: Francis Moreau Newsgroups: gmane.emacs.gnus.general Subject: Re: Improving Gnus speed Date: Sun, 21 Nov 2010 14:54:22 +0100 Message-ID: References: NNTP-Posting-Host: lo.gmane.org Mime-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: quoted-printable X-Trace: dough.gmane.org 1290347744 25983 80.91.229.12 (21 Nov 2010 13:55:44 GMT) X-Complaints-To: usenet@dough.gmane.org NNTP-Posting-Date: Sun, 21 Nov 2010 13:55:44 +0000 (UTC) To: ding@gnus.org Original-X-From: ding-owner+M22579@lists.math.uh.edu Sun Nov 21 14:55:41 2010 Return-path: Envelope-to: ding-account@gmane.org Original-Received: from util0.math.uh.edu ([129.7.128.18]) by lo.gmane.org with esmtp (Exim 4.69) (envelope-from ) id 1PKAOK-00038W-8g for ding-account@gmane.org; Sun, 21 Nov 2010 14:55:40 +0100 Original-Received: from localhost ([127.0.0.1] helo=lists.math.uh.edu) by util0.math.uh.edu with smtp (Exim 4.63) (envelope-from ) id 1PKANo-00052y-CB; Sun, 21 Nov 2010 07:55:08 -0600 Original-Received: from mx2.math.uh.edu ([129.7.128.33]) by util0.math.uh.edu with esmtps (TLSv1:AES256-SHA:256) (Exim 4.63) (envelope-from ) id 1PKANm-00052k-GK for ding@lists.math.uh.edu; Sun, 21 Nov 2010 07:55:06 -0600 Original-Received: from quimby.gnus.org ([80.91.231.51]) by mx2.math.uh.edu with esmtp (Exim 4.72) (envelope-from ) id 1PKANh-0002iy-9e for ding@lists.math.uh.edu; Sun, 21 Nov 2010 07:55:05 -0600 Original-Received: from mail-ww0-f42.google.com ([74.125.82.42]) by quimby.gnus.org with esmtp (Exim 3.36 #1 (Debian)) id 1PKANg-0000tz-00 for ; Sun, 21 Nov 2010 14:55:00 +0100 Original-Received: by wwb17 with SMTP id 17so2006931wwb.5 for ; Sun, 21 Nov 2010 05:54:29 -0800 (PST) DKIM-Signature: v=1; a=rsa-sha256; c=relaxed/relaxed; d=gmail.com; s=gamma; h=domainkey-signature:received:received:from:to:subject:references :date:in-reply-to:message-id:user-agent:mime-version:content-type :content-transfer-encoding; bh=z3Ypg0+DK80DDF8R6O52S4lyITZTXKpuIdyyiRL9EpI=; b=EuQ7WwniggKUpQGLDL5bxfgOa+fOrkOMB7QPVlN9XPPiFstY3uPav/jWs/vn4KdVJD ga8mO9AZQhGOUKfOxpAjEgln0sFDILstNaxSv3lJeIzuik7xoh3Uedjc9ZOD/KD5rDca UapsDwKl7v/F5hvYV3FlJQsAn+GvU2NX5SQgQ= DomainKey-Signature: a=rsa-sha1; c=nofws; d=gmail.com; s=gamma; h=from:to:subject:references:date:in-reply-to:message-id:user-agent :mime-version:content-type:content-transfer-encoding; b=D+yiqTBUHUknX2+HeSA8b6OxBlVcY/9wb48BacUeW+UjxYonb8ChpRW6vlI1/fBQeS Ma8FR51CE2Kz7DsXQPVF7mCwnNp9PKCIhlQphYVfFXTN7t3EKvQUc1ymrYhyTa3z7dLS LwUVIDDE+PcVpGccSqDghrMbZ0HeBbLddi0X0= Original-Received: by 10.216.21.9 with SMTP id q9mr4732105weq.3.1290347668047; Sun, 21 Nov 2010 05:54:28 -0800 (PST) Original-Received: from localhost (au213-1-82-235-205-153.fbx.proxad.net [82.235.205.153]) by mx.google.com with ESMTPS id f31sm1771220wej.15.2010.11.21.05.54.25 (version=TLSv1/SSLv3 cipher=RC4-MD5); Sun, 21 Nov 2010 05:54:26 -0800 (PST) In-Reply-To: (Lars Magne Ingebrigtsen's message of "Sun, 21 Nov 2010 07:02:05 +0100") User-Agent: Gnus/5.13 (Gnus v5.13) Emacs/23.2 (gnu/linux) X-Spam-Score: -2.0 (--) List-ID: Precedence: bulk Xref: news.gmane.org gmane.emacs.gnus.general:74214 Archived-At: Lars Magne Ingebrigtsen writes: > Francis Moreau writes: > >> For example, I have a group which contains 1298 articles. Entering it >> lasts 9 seconds: >> >> - 7 secs in the fetching process >> - 2 secs in sorting + summary buffer generation > > [...] > >> I'm giving you the elp results when entering in the group containing >> 1298 articles, with all articles displayed: >> >> gnus-thread-total-score 21809 18.6= 07898999 0.0008532211 > > elp is still claiming that this function is being called 21k times, and > that this takes 18 seconds. Which sort of doesn't make much sense, since: > >> gnus-group-select-group 1 10.6= 6085 10.66085 > > This is surely the top-level function being called, so the 18 seconds > sorting should be counted here, too, but it isn't, which is why I was > wondering whether you were doing other things between each > elp-results...=20 I agree with you, these numbers are crazy. Actually I was looking at the time elapsed, and I can't interpret them at all. It says 18.6 seconds where as the whole process lasts 10 seconds at most. I re-did several times the same benchmarks and the figures seem stable. > > Anyway: > >> gnus-get-newsgroup-headers-xover 1 6.78= 624 6.78624 > > There's the seven seconds... > Yes the longuest one > >> gnus-summary-number-of-articles-in-thread 22361 3.21= 77489999 0.0001439000 > > And there's a totally crazy call number again. That function should be > called (generally) once per displayed article in the summary buffer (if > you have that in your summary line format). But it's called 10x the > number of times. > >> gnus-dd-mmm 1301 0.94= 18810000 0.0007239669 > > I'm guessing you have a somewhat non-standard summary line format for > that function to show up, but at least it tallies up with the number of > articles in the group, somewhat. Here's my summary line format if that can help: (setq gnus-summary-line-format (concat "%0{%U%R%z%}" "%10{=E2=94=82%}" "%3t" "%10{=E2=94=82%}" "%(%-20,20f %)" "%10{=E2=94=82%}" "%1{%d%}" "%10{=E2=94=82%}" "%6V" "%10{=E2=94=82%}" "%10{%B%}" "%s\n")) =20=20 >> gnus-id-to-thread 21805 0.19= 13100000 8.773...e-06 > > Again, a totally ridiculous call number... > > I'd suggest removing all Gnus customisations to find out what triggers > these weird call numbers. Ok, I'm trying... Let's remove my summary line format customisation... and the numbers are now: gnus-topic-select-group 1 8.071814 8.071814 gnus-group-select-group 1 8.071757 8.071757 gnus-group-read-group 1 8.071748 8.071748 gnus-summary-read-group 1 8.071693 8.071693 gnus-summary-read-group-1 1 8.071681 8.071681 gnus-select-newsgroup 1 7.30483 7.30483 gnus-summary-limit-children 1324 7.1840629999 0.00542= 60294 gnus-fetch-headers 1 6.445576 6.445576 gnus-get-newsgroup-headers-xover 1 6.420683 6.420683 gnus-articles-to-read 1 0.818016 0.818016 gnus-summary-prepare 1 0.573297 0.573297 gnus-summary-prepare-threads 1 0.553526 0.553526 gnus-run-hooks 1337 0.2399320000 0.00017= 94554 gnus-summary-highlight-line 1326 0.2316510000 0.00017= 46990 gnus-sort-threads-recursive 837 0.1313619999 0.00015= 69438 gnus-summary-initial-limit 1 0.10973 0.10973 gnus-summary-highlight-line-0 1326 0.0972370000 7.333..= .e-05 gnus-summary-maybe-hide-threads 1 0.04301 0.04301 gnus-summary-hide-all-threads 1 0.043 0.043 gnus-summary-next-thread 44 0.0411179999 0.00093= 44999 gnus-summary-go-to-next-thread 44 0.040834 0.00092= 80454 gnus-retrieve-headers 2 0.03551 0.017755 gnus-map-function 1352 0.0294150000 2.175..= .e-05 which seem much more sensible. Now restoring my summary line format and removing only the "%6V" format specifier gives almost the same sane results. --=20 Francis