Addendum to trn problems: logging of server interaction

[Patrick Vervoorn]

Hello,

I have been permitted to access the INN newsserver again, and I have been
able to gather the following logging of the offending session. (See at the
bottom of my mail: it is quite large, sorry for that.)

I have mailed this report to both the trn as well as the inn-workers
mailing-lists, since I'm still not sure what is going on here.

I'm not really an expert, but interpreting the log myself, I see the
following strange things

The server itself at first reports the following as the range of valid
articles:

<alt.chello.binaries 0000384082 0000287442 y

Then trn starts by issueing an XOVER command, which seems to succeed OK:

>XOVER 287443-287762
<224 287443-287762 fields follow

However, then trn does something strange:

>STAT 287442
<423 Bad article number
>STAT
<503 Server closed connecton.

(Can it legally 'STAT' the lower of the reported numbers? It did start the
XOVER command with 'lower_number+1'?)

After that, things get worse: The server disconnects the client, trn
retries, and finally decides it should fall back to issueing HEAD and XHDR
commands, which do continue, but put a severe load on the newsserver.
Several hours later (not tried in this session), this process completes,
and I get a list with the new articles.

Does this shed some light on the matter? If trn is doing something wrong,
what is it? Is the INN server misconfigured, because it takes a long time
and a high load in order to respond to the HEAD and XHDR commands?

Best regards,

Patrick.

Addendum 1: Complete logging of session

<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease)
ready (posting ok).
[...]
>LIST active alt.chello.binaries
<215 Newsgroups in form "group high low flags".
<alt.chello.binaries 0000384082 0000287442 y
t 56003 alt.chello.binaries
[...]
Entering alt.chello.binaries:>GROUP alt.chello.binaries
<211 91479 287442 384093 alt.chello.binaries
>LIST active alt.chello.binaries
<215 Newsgroups in form "group high low flags".
<alt.chello.binaries 0000384093 0000287442 y
>XOVER 287443-287762
<224 287443-287762 fields follow

Getting overview file. [                         ]
>STAT 287442
<423 Bad article number
>STAT
<503 Server closed connecton.
>NEXT
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>MODE READER
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>GROUP alt.chello.binaries
<211 91545 287442 384159 alt.chello.binaries
>NEXT
<503 Server closed connecton.
>XOVER 287763-287783
<503 Server closed connecton.
>LISTGROUP
<503 Server closed connecton.

Scoring articles...
Reading score files...


Caching 56014 articles.>XOVER 287443-287762
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>MODE READER
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>GROUP alt.chello.binaries
<211 91581 287442 384195 alt.chello.binaries
>XOVER 287443-287762
<224 287443-287762 fields follow
>STAT 287442
<423 Bad article number
>STAT
<503 Server closed connecton.
>NEXT
<503 Server closed connecton.
>XOVER 287763-287783
<503 Server closed connecton.
 [                         ]
>HEAD 287443
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>MODE READER
<200 tudelft.nl InterNetNews NNRP server INN 2.3.1 (20001115 prerelease) ready (posting ok).
>GROUP alt.chello.binaries
<211 91628 287442 384242 alt.chello.binaries
>HEAD 287443
<423 Bad article number
>HEAD 287444
<423 Bad article number
>HEAD 287445
<423 Bad article number
>HEAD 287446
<423 Bad article number

[...]

>XHDR subject 287699-287703
<221 subject fields follow
<.
>XHDR subject 287704-287708
<221 subject fields follow
<.
>XHDR subject 287709-287713
<221 subject fields follow
<.

Best regards,

Patrick.