[leafnode-list] Re: [beta] select_group dead slow

[Christian Ebert]

* Christian Ebert on Monday, September 04, 2006 at 03:07:05 +0200:
> * Matthias Andree on Monday, September 04, 2006 at 01:40:34 +0200:
>> What is slrn sending in the "slow" case?
> 
> To judge from the log sent in my other mail, slrn issues XOVER
> without the GROUP command first.
> 
> 2 snippets from news.debug:
> 
> Sep  4 02:22:10 krille leafnode[970]: <MODE READER
> Sep  4 02:22:10 krille leafnode[970]: >200 Leafnode 2.0.0.alpha20060831a, pleased to meet you!
> Sep  4 02:22:10 krille leafnode[970]: <XOVER
> Sep  4 02:22:10 krille leafnode[970]: >412 Use the GROUP command first
> Sep  4 02:22:10 krille leafnode[970]: <LIST OVERVIEW.FMT
> Sep  4 02:22:10 krille leafnode[970]: >215 information follows
> Sep  4 02:22:10 krille leafnode[970]: <NEWGROUPS 060904 001052 GMT
> Sep  4 02:22:10 krille leafnode[970]: >231 List of new newsgroups since 1157328652 follows
> Sep  4 02:22:10 krille leafnode[970]: <LIST
> Sep  4 02:22:10 krille leafnode[970]: >215 Newsgroups in form "group high low flags".
> Sep  4 02:22:38 krille leafnode[970]: <GROUP de.comp.text.tex
> Sep  4 02:22:38 krille leafnode[970]: Cannot open .overview.deleted for reading: No such file or directory
> Sep  4 02:22:38 krille leafnode[970]: >211 1109 2 20080 de.comp.text.tex group selected
> Sep  4 02:22:38 krille leafnode[970]: <XOVER 19202-19202
> Sep  4 02:22:38 krille leafnode[970]: Cannot open .overview.deleted for reading: No such file or directory
> Sep  4 02:22:38 krille leafnode[970]: >224 Overview information for postings 19202-19202:
> Sep  4 02:22:38 krille leafnode[970]: <XOVER 19316-19316
> Sep  4 02:22:38 krille leafnode[970]: >224 Overview information for postings 19316-19316:
> Sep  4 02:22:38 krille leafnode[970]: <XOVER 19496-19496
> Sep  4 02:22:38 krille leafnode[970]: >224 Overview information for postings 19496-19496:
> 
> and so on, 1 command per header, whereas in the "fast" case
> (selecting group with all, unread *and* read, messages:
> 
> Sep  4 02:23:01 krille leafnode[970]: <GROUP comp.text.tex
> Sep  4 02:23:01 krille leafnode[970]: Cannot open .overview.deleted for reading: No such file or directory
> Sep  4 02:23:01 krille leafnode[970]: >211 1403 2 19405 comp.text.tex group selected
> Sep  4 02:23:01 krille leafnode[970]: <XOVER 2-19405
> Sep  4 02:23:01 krille leafnode[970]: Cannot open .overview.deleted for reading: No such file or directory
> Sep  4 02:23:01 krille leafnode[970]: >224 Overview information for postings 2-19405:

Hm. Apart from the log format I can't detect any difference to
leafnode-1 (but I am the contrary of an expert, heh):


Sep  4 09:33:24 krille leafnode[2344]: <MODE READER
Sep  4 09:33:24 krille leafnode[2344]: >200 Leafnode 1.11.5, pleased to meet you!
Sep  4 09:33:24 krille leafnode[2344]: <XOVER
Sep  4 09:33:24 krille leafnode[2344]: >412 Use the GROUP command first
Sep  4 09:33:24 krille leafnode[2344]: <LIST OVERVIEW.FMT
Sep  4 09:33:24 krille leafnode[2344]: >215 information follows
Sep  4 09:33:24 krille leafnode[2344]: <NEWGROUPS 060904 004406 GMT
Sep  4 09:33:24 krille leafnode[2344]: 231 List of new newsgroups since 1157330646 follows
Sep  4 09:33:24 krille leafnode[2344]: <LIST
Sep  4 09:33:24 krille leafnode[2344]: >215 Newsgroups in form "group high low flags".
Sep  4 09:33:29 krille leafnode[2344]: <GROUP de.comp.text.tex
Sep  4 09:33:29 krille leafnode[2344]: marked group de.comp.text.tex interesting
Sep  4 09:33:29 krille leafnode[2344]: markinterest: de.comp.text.tex touched ctime
Sep  4 09:33:29 krille leafnode[2344]: >211 20081 2 20082 de.comp.text.tex group selected
Sep  4 09:33:29 krille leafnode[2344]: <XOVER 19202-19202
Sep  4 09:33:29 krille leafnode[2344]: markinterest: de.comp.text.tex touched ctime
Sep  4 09:33:29 krille leafnode[2344]: >224 Overview information for postings 19202-19202:
Sep  4 09:33:29 krille leafnode[2344]: <XOVER 19316-19316
Sep  4 09:33:29 krille leafnode[2344]: markinterest: de.comp.text.tex touched ctime
Sep  4 09:33:29 krille leafnode[2344]: >224 Overview information for postings 19316-19316:
Sep  4 09:33:29 krille leafnode[2344]: <XOVER 19496-19496
Sep  4 09:33:29 krille leafnode[2344]: markinterest: de.comp.text.tex touched ctime
Sep  4 09:33:29 krille leafnode[2344]: >224 Overview information for postings 19496-19496:
Sep  4 09:33:29 krille leafnode[2344]: <XOVER 19636-19636
Sep  4 09:33:29 krille leafnode[2344]: markinterest: de.comp.text.tex touched ctime

[...]

Sep  4 09:33:35 krille leafnode[2344]: <GROUP comp.text.tex
Sep  4 09:33:35 krille leafnode[2344]: marked group comp.text.tex interesting
Sep  4 09:33:35 krille leafnode[2344]: markinterest: comp.text.tex touched ctime
Sep  4 09:33:35 krille leafnode[2344]: >211 19404 2 19405 comp.text.tex group selected
Sep  4 09:33:35 krille leafnode[2344]: <XOVER 2-19405
Sep  4 09:33:35 krille leafnode[2344]: markinterest: comp.text.tex touched ctime
Sep  4 09:33:35 krille leafnode[2344]: >224 Overview information for postings 2-19405:
Sep  4 09:33:41 krille leafnode[2344]: <QUIT
Sep  4 09:33:41 krille leafnode[2344]: >205 Always happy to serve!

I uploaded tarballs of the 2 complete logs at:

<http://www.blacktrash.org/downloads/leafnode_debug/>

They are called news.debug-2 (for leafnode-2) and news.debug-1
(for leafnode-1) respectively.

The actions of slrn were:

launch
enter de.comp.text.tex (only unread)
enter comp.text.tex (unread + read)
quit

c
-- 
_B A U S T E L L E N_ lesen! --->> <http://www.blacktrash.org/baustellen.html>