From mboxrd@z Thu Jan 1 00:00:00 1970 X-Msuck: nntp://news.gmane.io/gmane.emacs.gnus.general/59104 Path: main.gmane.org!not-for-mail From: Romain Francoise Newsgroups: gmane.emacs.gnus.general Subject: Re: Gnus slow? Date: Thu, 11 Nov 2004 12:47:46 +0100 Organization: orebokech dot com Message-ID: <87r7n0zjx9.fsf@orebokech.com> References: <87sm7hua65.fsf@orebokech.com> NNTP-Posting-Host: deer.gmane.org Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii X-Trace: sea.gmane.org 1100173735 32510 80.91.229.6 (11 Nov 2004 11:48:55 GMT) X-Complaints-To: usenet@sea.gmane.org NNTP-Posting-Date: Thu, 11 Nov 2004 11:48:55 +0000 (UTC) Original-X-From: ding-owner+M7646@lists.math.uh.edu Thu Nov 11 12:48:41 2004 Return-path: Original-Received: from malifon.math.uh.edu ([129.7.128.13] ident=mail) by deer.gmane.org with esmtp (Exim 3.35 #1 (Debian)) id 1CSDRQ-0004rL-00 for ; Thu, 11 Nov 2004 12:48:40 +0100 Original-Received: from localhost ([127.0.0.1] helo=lists.math.uh.edu ident=lists) by malifon.math.uh.edu with smtp (Exim 3.20 #1) id 1CSDQk-000105-00; Thu, 11 Nov 2004 05:47:58 -0600 Original-Received: from util2.math.uh.edu ([129.7.128.23]) by malifon.math.uh.edu with esmtp (Exim 3.20 #1) id 1CSDQf-000100-00 for ding@lists.math.uh.edu; Thu, 11 Nov 2004 05:47:53 -0600 Original-Received: from justine.libertine.org ([66.139.78.221] ident=postfix) by util2.math.uh.edu with esmtp (Exim 4.30) id 1CSDQd-0002Kd-5q for ding@lists.math.uh.edu; Thu, 11 Nov 2004 05:47:51 -0600 Original-Received: from yeast.orebokech.com (yeast.orebokech.com [82.67.41.165]) by justine.libertine.org (Postfix) with ESMTP id 70C3F3A003A for ; Thu, 11 Nov 2004 05:47:48 -0600 (CST) Original-Received: from pacem.orebokech.com (pacem.orebokech.com [192.168.1.3]) by yeast.orebokech.com (Postfix) with ESMTP id B654A12CF8 for ; Thu, 11 Nov 2004 12:47:46 +0100 (CET) Original-Received: by pacem.orebokech.com (Postfix, from userid 1000) id 841E4B2FF6; Thu, 11 Nov 2004 12:47:46 +0100 (CET) Original-To: ding@gnus.org Mail-Copies-To: nobody Face: iVBORw0KGgoAAAANSUhEUgAAADAAAAAwAgMAAAAqbBEUAAAADFBMVEWb0fQdJkVorOdOfLM6 SWwSAAAB+klEQVQokSXRsW/TUBAG8K+2KiCVR8TaJVLlKmLBMGLoP5CBq6uKPFeMMGAxWmpilamt MrIHJCTnotRTlojWpmxMDLgsEWSMzMJigRwccy7Lk3763jvdvYO7bK85GfOIliWcZdtQLEh7gspJ s0g0cXPBjjNaCcYCt7TVqGLWhk6OXpn4ZxeCMf1GlR/604JDM6UKVZkXvOJwRLTCj3LNHDEHY6Ku FNC39KoImjWqUt+MeTUwdmtMErlVsZFQDy7ZCXOcGTvkouN9+Rx/W2b9w48KhHvZbN0v+smaQo4N TqxucQy9BvivIfifBNvnVEzf2rcUyuRDI1rx9J0tSWnf12LmiyNb3vz0bkBGi18fbCjMZ0ZgCv7g WGHwIHUk4YF2qhBqKclPMbShAFZfXSdDH1loPb6qO7WOfSyCVvsOR+QmxnMsNONJM2uqOVoZQnO9 1/fezOOkucCUrUef2jfHTvA+RMy3d17NYM0xEYRk700AGM4A5rBjK8vGQ1IBtMmLgzR3U3KyI5zS idfKiRw2A9C+5jWW1P0ebAGU6J4mvX3FJtCG3m4IrqQgDqA/bdTLDgQ2NOeohrpOkF4KHPKuE6sr 2E+pgwSNlkwa7UdENe7SJUd+FlWQhnjyjEf+MiecFFGR73H0MpVlRXKUu+Z5SbvAtjTZIT4n+oV/ mo4eYrnhXgYAAAAASUVORK5CYII= X-Now-Playing: Townes Van Zandt's _Townes Van Zandt_: "Waiting around to die" In-Reply-To: (Katsumi Yamaoka's message of "Thu, 11 Nov 2004 08:40:15 +0900") User-Agent: Gnus/5.110003 (No Gnus v0.3) Emacs/21.3.50 (gnu/linux) Precedence: bulk Original-Sender: ding-owner@lists.math.uh.edu Xref: main.gmane.org gmane.emacs.gnus.general:59104 X-Report-Spam: http://spam.gmane.org/gmane.emacs.gnus.general:59104 Here are the ELP results. In all cases I started a new Emacs instance, started Gnus, started profiling, hit `g', then stopped profiling at the "Checking new news...done" message. The two Gnus versions tested are a No Gnus checkout as of May 12th (which I will name version A) and the latest CVS from this morning (version B). Four tests are performed: for the `gnus' and the `nntp' package, for versions A and B. I have one nnml server (but no new articles for it), and three nntp servers: news.free.fr, news.gmane.org and news.gnus.org. Package gnus, version A (first 10 lines): Function Name Call Count Elapsed Time Average Time ================================ ========== ============ ============ gnus-group-get-new-news 1 1.465511 1.465511 gnus-get-unread-articles 1 1.277827 1.277827 gnus-activate-group 405 0.6969249999 0.0017208024 gnus-request-scan 200 0.5125570000 0.0025627850 gnus-topic-prepare-topic 44 0.509935 0.0115894318 gnus-read-active-file-2 2 0.469293 0.2346465 gnus-retrieve-groups 2 0.464826 0.232413 gnus-group-list-groups 1 0.1848060000 0.1848060000 Package gnus, version B (first 10 lines): Function Name Call Count Elapsed Time Average Time ================================ ========== ============ ============ gnus-group-get-new-news 1 29.122044 29.122044 gnus-get-unread-articles 1 28.808084 28.808084 gnus-activate-group 808 28.273399000 0.0349918304 gnus-topic-prepare-topic 44 0.7627989999 0.0173363409 gnus-request-scan 200 0.5203380000 0.0026016900 gnus-read-active-file-2 2 0.402818 0.201409 gnus-retrieve-groups 2 0.398634 0.199317 gnus-group-list-groups 1 0.310868 0.310868 Package nntp, version A: Function Name Call Count Elapsed Time Average Time ================================ ========== ============ ============ nntp-accept-process-output 1592 1.5516599999 0.0009746608 nntp-retrieve-groups 2 1.5244879999 0.7622439999 nntp-accept-response 1525 1.502601 0.0009853121 nntp-request-group 1 0.065724 0.065724 nntp-find-connection-buffer 1598 0.0067499999 4.224...e-06 nntp-erase-buffer 205 0.0009970000 4.863...e-06 nntp-open-server 3 0.0002960000 9.866...e-05 nntp-server-opened 11 6.2e-05 5.636...e-06 nntp-possibly-change-group 3 5.3e-05 1.766...e-05 nntp-decode-text 1 1.5e-05 1.5e-05 nntp-close-group 1 4e-06 4e-06 Package nntp, version B: Function Name Call Count Elapsed Time Average Time ================================ ========== ============ ============ nntp-accept-process-output 40433 39.736943999 0.0009827849 nntp-request-group 404 39.094312999 0.0967681014 nntp-retrieve-groups 2 1.069817 0.5349085 nntp-accept-response 1075 1.0523970000 0.0009789739 nntp-find-connection-buffer 40439 0.1772040000 4.382...e-06 nntp-erase-buffer 1011 0.0073319999 7.252...e-06 nntp-possibly-change-group 406 0.0068940000 1.698...e-05 nntp-server-opened 820 0.0052629999 6.418...e-06 nntp-decode-text 404 0.0048309999 1.195...e-05 nntp-request-update-info 404 0.0015660000 3.876...e-06 nntp-open-server 6 0.000618 0.000103 nntp-close-group 1 4e-06 4e-06 My observations: - with version B, fetching articles takes 29s in the first run, more than 40s in the second where version A takes 1.5s. - version B has a lot more of NNTP activity: it call nntp-request-group for each group. - version B activates twice as many groups as version A. Cheers, -- Romain Francoise | Sometimes I don't know where it's a miracle -- http://orebokech.com/ | this dirty road is taking me, | sometimes I can't even see | the reason why.