From mboxrd@z Thu Jan 1 00:00:00 1970 X-Msuck: nntp://news.gmane.io/gmane.emacs.gnus.general/41001 Path: main.gmane.org!not-for-mail From: Russ Allbery Newsgroups: gmane.emacs.gnus.general Subject: Re: Checking new mail very slow with current CVS Date: Thu, 27 Dec 2001 02:04:34 -0800 Organization: The Eyrie Sender: owner-ding@hpc.uh.edu Message-ID: References: NNTP-Posting-Host: coloc-standby.netfonds.no Mime-Version: 1.0 Content-Type: text/plain; charset=us-ascii X-Trace: main.gmane.org 1035176469 3911 80.91.224.250 (21 Oct 2002 05:01:09 GMT) X-Complaints-To: usenet@main.gmane.org NNTP-Posting-Date: Mon, 21 Oct 2002 05:01:09 +0000 (UTC) Return-Path: Original-Received: (qmail 2886 invoked from network); 27 Dec 2001 10:06:12 -0000 Original-Received: from malifon.math.uh.edu (mail@129.7.128.13) by mastaler.com with SMTP; 27 Dec 2001 10:06:12 -0000 Original-Received: from sina.hpc.uh.edu ([129.7.128.10] ident=lists) by malifon.math.uh.edu with esmtp (Exim 3.20 #1) id 16JXPN-0004rd-00; Thu, 27 Dec 2001 04:05:05 -0600 Original-Received: by sina.hpc.uh.edu (TLB v0.09a (1.20 tibbs 1996/10/09 22:03:07)); Thu, 27 Dec 2001 04:04:54 -0600 (CST) Original-Received: from sclp3.sclp.com (qmailr@sclp3.sclp.com [209.196.61.66]) by sina.hpc.uh.edu (8.9.3/8.9.3) with SMTP id EAA16637 for ; Thu, 27 Dec 2001 04:04:36 -0600 (CST) Original-Received: (qmail 2874 invoked by alias); 27 Dec 2001 10:04:41 -0000 Original-Received: (qmail 2869 invoked from network); 27 Dec 2001 10:04:40 -0000 Original-Received: from windlord.stanford.edu (171.64.13.23) by gnus.org with SMTP; 27 Dec 2001 10:04:40 -0000 Original-Received: (qmail 24328 invoked by uid 50); 27 Dec 2001 10:04:34 -0000 Original-To: ding@gnus.org In-Reply-To: (Simon Josefsson's message of "Wed, 26 Dec 2001 14:56:01 +0100") Original-Lines: 269 User-Agent: Gnus/5.090004 (Oort Gnus v0.04) XEmacs/21.1 (Channel Islands, sparc-sun-solaris2.6) Precedence: list X-Majordomo: 1.94.jlt7 Xref: main.gmane.org gmane.emacs.gnus.general:41001 X-Report-Spam: http://spam.gmane.org/gmane.emacs.gnus.general:41001 Simon Josefsson writes: > Then probably the marks stuff isn't to blame. Hm. For comparison, > could you get the similar elp results from Gnus 5.8.7? (Be sure to not > read lots of mail using it, those marks will be lost when you revert to > Oort Gnus. Maybe I should fix this.) Okay, the new elp helped me track down part of the problem; 1 second of the time was nndiary. I only had one article in it, so that seems a bit excessive. I've stopped using that for the time being, since the delay is more annoying to me than the new features it offers. However, the speed difference is still very noticeable. What follows is two elp-results from instrumenting both "gnus" and "nn"; the first is from Oort (CVS pull as of a few days ago) and the second is from 5.8.7. Note that checking new mail takes 3.9 seconds for Oort and 0.9 seconds for Gnus 5.8.7, so it's about 4.7 times slower; my estimate was just about right on. Note also that 5.8.7 never runs a whole bunch of functions that Oort runs several hundred times, apparently once for every group that I have in my newsrc.eld. Function Name Call Count Elapsed Time Average Time ====================================== ========== ============ ============ gnus-group-get-new-news 1 3.883442 3.883442 gnus-get-unread-articles 1 1.965818 1.965818 gnus-read-active-file 1 1.656048 1.656048 gnus-read-active-file-1 3 1.654937 0.5516456666 gnus-read-active-file-2 1 1.434889 1.434889 gnus-retrieve-groups 1 1.388825 1.388825 nntp-retrieve-groups 1 1.388692 1.388692 nntp-accept-response 251 1.277373 0.0050891354 nntp-accept-process-output 251 1.259354 0.0050173466 nnmail-group-pathname 644 1.2270809999 0.0019054052 nnml-possibly-change-directory 427 0.9667099999 0.0022639578 nnml-request-update-info 213 0.9637049999 0.0045244366 gnus-activate-group 215 0.8194799999 0.0038115348 nnml-request-group 213 0.5900420000 0.0027701502 nnml-marks-changed-p 213 0.5039689999 0.0023660516 gnus-group-list-groups 1 0.260599 0.260599 gnus-group-prepare-flat 1 0.254833 0.254833 nnml-server-opened 641 0.1659939999 0.0002589609 gnus-server-opened 220 0.1468420000 0.0006674636 nnoo-server-opened 647 0.1366290000 0.0002111731 gnus-request-list 2 0.133441 0.0667205 nnml-request-list 1 0.13243 0.13243 nnmail-get-active 2 0.130874 0.065437 nnmail-parse-active 2 0.130755 0.0653775000 gnus-group-insert-group-line 30 0.11916 0.0039720000 gnus-group-prepare-logic 479 0.1026369999 0.0002142734 gnus-active-to-gnus-format 3 0.0843640000 0.0281213333 nnheader-message 144 0.0661179999 0.0004591527 gnus-run-hooks 33 0.0455109999 0.0013791212 gnus-group-highlight-line 30 0.0435869999 0.0014529 nnheader-replace-duplicate-chars-in-st 644 0.0382320000 5.936...e-05 gnus-check-backend-function 464 0.0295479999 6.368...e-05 gnus-server-to-method 316 0.0217809999 6.892...e-05 gnus-range-length 210 0.0177750000 8.464...e-05 gnus-add-text-properties 30 0.015636 0.0005212 gnus-put-text-property-excluding-chara 30 0.015017 0.0005005666 gnus-put-text-property 60 0.014384 0.0002397333 gnus-xmas-add-text-properties 30 0.014309 0.0004769666 nnheader-translate-file-chars 644 0.0133309999 2.070...e-05 gnus-get-function 436 0.0126889999 2.910...e-05 gnus-server-extend-method 443 0.0119769999 2.703...e-05 nnheader-report 217 0.011825 5.449...e-05 gnus-xmas-put-text-property 60 0.0117139999 0.0001952333 gnus-request-scan 2 0.0089 0.00445 gnus-server-get-method 474 0.0087679999 1.849...e-05 nnml-request-scan 1 0.0081600000 0.0081600000 nnmail-get-new-mail 2 0.008041 0.0040205 gnus-uncompress-range 210 0.0078769999 3.750...e-05 nndraft-request-group 2 0.006551 0.0032755 nntp-find-connection-buffer 254 0.0064679999 2.546...e-05 nnoo-parent-function 3 0.005563 0.0018543333 gnus-group-timestamp-delta 30 0.005466 0.0001822 gnus-correct-length 60 0.0052110000 8.685...e-05 gnus-message 9 0.004787 0.0005318888 nnoo-current-server 654 0.0047260000 7.226...e-06 gnus-point-at-eol 715 0.0044679999 6.248...e-06 gnus-update-format-specifications 1 0.004287 0.004287 nnmh-request-group 2 0.004005 0.0020025 gnus-byte-compile 1 0.003837 0.003837 gnus-group-group-name 31 0.0035369999 0.0001140967 gnus-parse-format 2 0.00351 0.001755 gnus-group-get-parameter 30 0.0034529999 0.0001151 gnus-extent-start-open 30 0.002645 8.816...e-05 gnus-parse-complex-format 1 0.002509 0.002509 gnus-complex-form-to-spec 2 0.002336 0.001168 gnus-parse-simple-format 4 0.00229 0.0005725 nndraft-request-update-info 2 0.002257 0.0011285 gnus-server-status 220 0.0022020000 1.000...e-05 nnmail-find-file 2 0.002086 0.001043 gnus-server-add-address 63 0.001772 2.812...e-05 gnus-group-parameter-value 30 0.001677 5.590...e-05 gnus-check-server 5 0.001611 0.0003222 gnus-xmas-extent-start-open 30 0.0014 4.666...e-05 nnmh-possibly-change-directory 2 0.001399 0.0006995 nnheader-init-server-buffer 3 0.001346 0.0004486666 gnus-char-width 71 0.001244 1.752...e-05 gnus-group-name-charset 30 0.001194 3.98e-05 gnus-group-prefixed-name 4 0.001186 0.0002965 nndraft-possibly-change-group 4 0.001181 0.0002952499 nndraft-open-server 2 0.000924 0.000462 nnmail-activate 1 0.000853 0.000853 nnoo-change-server 5 0.0008430000 0.0001686 nndiary-request-list 1 0.00076 0.00076 gnus-group-name-decode 60 0.0007439999 1.239...e-05 nndraft-close-group 1 0.000614 0.000614 nntp-server-opened 4 0.000578 0.0001445 nndraft-articles 2 0.000553 0.0002765 gnus-group-set-mode-line 2 0.000547 0.0002735 nndiary-request-scan 1 0.000476 0.000476 gnus-server-equal 9 0.000433 4.811...e-05 gnus-find-method-for-group 7 0.000432 6.171...e-05 gnus-continuum-version 1 0.000357 0.000357 gnus-master-read-slave-newsrc 1 0.000332 0.000332 nntp-possibly-change-group 1 0.000299 0.000299 nnmh-server-opened 2 0.000287 0.0001435 gnus-group-position-point 1 0.000263 0.000263 nndiary-server-opened 2 0.000223 0.0001115 gnus-group-remove-excess-properties 30 0.0002210000 7.366...e-06 gnus-goto-colon 1 0.00022 0.00022 nndraft-server-opened 2 0.000217 0.0001085 gnus-mode-line-buffer-identification 2 0.000209 0.0001045 gnus-point-at-bol 32 0.0001860000 5.812...e-06 gnus-set-work-buffer 5 0.00018 3.599...e-05 gnus-methods-using 4 0.000162 4.05e-05 nndiary-possibly-change-directory 1 0.000161 0.000161 gnus-make-directory 2 0.000149 7.449...e-05 gnus-buffer-live-p 11 0.000139 1.263...e-05 nnheader-insert 1 0.000133 0.000133 gnus-update-read-articles 2 0.000132 6.600...e-05 gnus-xmas-mode-line-buffer-identificat 2 0.000114 5.7e-05 gnus-parse-active 1 0.000109 0.000109 nnoo-backend 3 0.000103 3.433...e-05 nnoo-parents 11 9.500...e-05 8.636...e-06 nnmail-get-value 2 7.500...e-05 3.75e-05 gnus-group-setup-buffer 1 6.1e-05 6.1e-05 gnus-display-time-event-handler 1 6e-05 6e-05 nnheader-concat 2 5.6e-05 2.8e-05 gnus-correct-pad-form 2 4.7e-05 2.35e-05 nnoo-variables 5 4.4e-05 8.8e-06 gnus-group-default-level 2 3e-05 1.5e-05 nnheader-re-read-dir 2 2.8e-05 1.4e-05 gnus-undo-boundary 2 2.7e-05 1.35e-05 nntp-find-connection 1 2.3e-05 2.3e-05 gnus-mouse-face-function 1 1.6e-05 1.6e-05 nnmail-cache-close 1 1.6e-05 1.6e-05 gnus-boundp 1 1.3e-05 1.3e-05 gnus-get-buffer-create 1 1.3e-05 1.3e-05 nnmail-cache-open 1 1.3e-05 1.3e-05 nnmh-close-group 1 9e-06 9e-06 gnus-archive-server-wanted-p 1 8e-06 8e-06 Function Name Call Count Elapsed Time Average Time ====================================== ========== ============ ============ gnus-group-get-new-news 1 0.832394 0.832394 gnus-read-active-file 1 0.486088 0.486088 gnus-read-active-file-1 2 0.485121 0.2425605 gnus-read-active-file-2 1 0.334234 0.334234 gnus-group-list-groups 1 0.217655 0.217655 gnus-group-prepare-flat 1 0.210033 0.210033 gnus-retrieve-groups 1 0.2022969999 0.2022969999 nntp-retrieve-groups 1 0.202164 0.202164 gnus-group-insert-group-line 31 0.20207 0.0065183870 gnus-active-to-gnus-format 2 0.170295 0.0851475 gnus-get-unread-articles 1 0.127812 0.127812 nntp-accept-response 3 0.102748 0.0342493333 nntp-accept-process-output 3 0.102511 0.0341703333 gnus-point-at-eol 947 0.0928039999 9.799...e-05 nnml-request-list 2 0.060771 0.0303855 nnmail-get-active 2 0.057748 0.028874 nnmail-parse-active 2 0.057629 0.0288145 gnus-run-hooks 34 0.0513209999 0.0015094411 gnus-group-highlight-line 31 0.0492290000 0.0015880322 gnus-request-scan 1 0.043761 0.043761 nnml-request-scan 1 0.043624 0.043624 nnmail-get-new-mail 1 0.043335 0.043335 nnmail-activate 1 0.031564 0.031564 gnus-request-list 1 0.030109 0.030109 gnus-range-length 217 0.0204670000 9.431...e-05 gnus-put-text-property-excluding-chara 31 0.017611 0.0005680967 gnus-put-text-property 62 0.0173499999 0.0002798387 gnus-add-text-properties 31 0.016529 0.0005331935 gnus-server-to-method 248 0.015055 6.070...e-05 gnus-xmas-add-text-properties 31 0.015027 0.0004847419 gnus-xmas-put-text-property 62 0.013915 0.0002244354 gnus-server-get-method 458 0.0098189999 2.143...e-05 gnus-uncompress-range 217 0.0087940000 4.052...e-05 nnmail-cache-open 1 0.008541 0.008541 gnus-server-extend-method 299 0.0084680000 2.832...e-05 nnheader-insert-file-contents 1 0.0081880000 0.0081880000 gnus-activate-group 2 0.006648 0.003324 gnus-group-timestamp-delta 31 0.0063489999 0.0002048064 nnoo-parent-function 3 0.005591 0.0018636666 nndraft-request-group 2 0.005158 0.002579 gnus-group-get-parameter 31 0.0040149999 0.0001295161 gnus-group-group-name 32 0.003924 0.000122625 nnmh-request-group 2 0.003831 0.0019155 gnus-message 6 0.00321 0.000535 gnus-extent-start-open 31 0.0030330000 9.783...e-05 nnmail-find-file 2 0.002793 0.0013965 nnheader-message 4 0.002443 0.0006107500 nndraft-request-update-info 2 0.00235 0.001175 gnus-server-add-address 63 0.0019380000 3.076...e-05 gnus-group-parameter-value 31 0.0018500000 5.967...e-05 gnus-xmas-extent-start-open 31 0.001615 5.209...e-05 gnus-server-opened 6 0.001509 0.0002515 nnmail-group-pathname 4 0.001491 0.00037275 nnmh-possibly-change-directory 2 0.001384 0.0006919999 gnus-check-server 4 0.001242 0.0003105 nnheader-init-server-buffer 3 0.000967 0.0003223333 gnus-group-prefixed-name 3 0.000934 0.0003113333 nnoo-change-server 4 0.000685 0.00017125 gnus-correct-length 62 0.0006330000 1.020...e-05 nndraft-possibly-change-group 2 0.00062 0.00031 nndraft-close-group 1 0.000588 0.000588 gnus-group-set-mode-line 2 0.000553 0.0002765 nndraft-articles 2 0.000536 0.000268 nndraft-open-server 1 0.000479 0.000479 nnoo-server-opened 6 0.000468 7.8e-05 nntp-server-opened 4 0.000421 0.00010525 gnus-master-read-slave-newsrc 1 0.000396 0.000396 gnus-find-method-for-group 7 0.000376 5.371...e-05 gnus-server-equal 7 0.000359 5.128...e-05 gnus-group-remove-excess-properties 31 0.0003020000 9.741...e-06 nnmh-server-opened 2 0.000281 0.0001405 gnus-point-at-bol 33 0.0002630000 7.969...e-06 nntp-possibly-change-group 1 0.000262 0.000262 nnml-server-opened 2 0.000255 0.0001275 nndraft-server-opened 2 0.00024 0.00012 gnus-mode-line-buffer-identification 2 0.000222 0.000111 gnus-update-format-specifications 1 0.00021 0.00021 gnus-get-function 6 0.000194 3.233...e-05 nnml-possibly-change-directory 1 0.000188 0.000188 nntp-find-connection-buffer 6 0.000186 3.1e-05 nnheader-replace-duplicate-chars-in-st 4 0.000172 4.3e-05 nnheader-report 3 0.000166 5.533...e-05 gnus-update-read-articles 2 0.000146 7.3e-05 gnus-make-directory 2 0.000142 7.1e-05 gnus-parse-active 2 0.000133 6.649...e-05 nnoo-current-server 13 0.000132 1.015...e-05 nnheader-replace-chars-in-string 4 0.000131 3.275e-05 nnoo-backend 3 0.000131 4.366...e-05 gnus-group-position-point 1 0.000125 0.000125 gnus-xmas-mode-line-buffer-identificat 2 0.000124 6.199...e-05 gnus-methods-using 3 0.000123 4.1e-05 nnoo-parents 10 0.000118 1.18e-05 nnheader-insert 1 0.000114 0.000114 nnheader-translate-file-chars 4 8.3e-05 2.075e-05 gnus-server-status 6 8.2e-05 1.366...e-05 gnus-goto-colon 1 8.000...e-05 8.000...e-05 gnus-check-backend-function 1 6.999...e-05 6.999...e-05 gnus-group-setup-buffer 1 6.999...e-05 6.999...e-05 gnus-buffer-live-p 3 5.099...e-05 1.7e-05 nnoo-variables 4 4.8e-05 1.2e-05 nnmail-get-value 1 4.1e-05 4.1e-05 nnheader-concat 1 3.3e-05 3.3e-05 gnus-group-default-level 2 3.1e-05 1.55e-05 nntp-find-connection 1 2.6e-05 2.6e-05 nnmail-cache-close 1 2e-05 2e-05 nnheader-re-read-dir 1 1.8e-05 1.8e-05 gnus-get-buffer-create 1 1.5e-05 1.5e-05 gnus-archive-server-wanted-p 1 1e-05 1e-05 nnmh-close-group 1 1e-05 1e-05 -- Russ Allbery (rra@stanford.edu)