[tpop3d-discuss] Long Login delay

Chris Lightfoot chris at ex-parrot.com
Tue, 20 Jan 2004 16:31:07 +0000


On Tue, Jan 20, 2004 at 04:50:49PM +0100, Ian Armstrong=A0 wrote:
> Here is a section of my log file just after I logged in.

In this case we have:

| 16:44:40: connection_sendresponse: client [6]127.0.0.1/expressmail.dk: =
sent `+OK <fddb82f078bf91426003209527b1a298@expressmail.dk>'
| 16:44:40: listeners_post_select: client [6]127.0.0.1/expressmail.dk: co=
nnected to local address 127.0.0.1:110
| 16:44:40: connection_parsecommand: client [6]127.0.0.1/expressmail.dk: =
received `CAPA'
| 16:44:40: connection_sendresponse: client [6]127.0.0.1/expressmail.dk: =
sent `+OK Capability list follows'
| 16:44:41: connection_parsecommand: client [6]127.0.0.1/expressmail.dk: =
received `USER ika@expressmail.dk'
| 16:44:41: connection_sendresponse: client [6]127.0.0.1/expressmail.dk: =
sent `+OK Tell me your password.'
| 16:44:41: connection_parsecommand: client [6]127.0.0.1/expressmail.dk: =
received `PASS [...]'
| 16:44:41: authcache_new_user_pass: returning saved entry for [ika@expre=
ssmail.dk; ika@expressmail.dk] (19s old) from slot 72
| 16:44:41: fork_child: [6]ika@expressmail.dk(127.0.0.1): began session f=
or `ika@expressmail.dk' with mysql+cache; child PID is 5929
| 16:44:41: auth_mysql_onlogin: SQL query: update passwd set now =3D now(=
) where id =3D 'ika@expressmail.dk'
| 16:44:41: maildir_new: scanned maildir /home/vmail/expressmail.dk/i/ika=
/ (328 messages) in 0.005s
| 16:44:41: connection_sendresponse: client [6]ika@expressmail.dk(127.0.0=
.1): sent `+OK Welcome aboard! You have 328 messages.'
| 16:44:41: connection_parsecommand: client [6]ika@expressmail.dk(127.0.0=
.1): received `CAPA'
| 16:44:41: connection_sendresponse: client [6]ika@expressmail.dk(127.0.0=
.1): sent `-ERR Do you actually know how to use this thing?'
| 16:44:41: connection_parsecommand: client [6]ika@expressmail.dk(127.0.0=
.1): received `STAT'
| 16:44:41: connection_sendresponse: client [6]ika@expressmail.dk(127.0.0=
.1): sent `+OK 328 1303883'
| 16:44:41: connection_parsecommand: client [6]ika@expressmail.dk(127.0.0=
.1): received `RETR 328'
| 16:44:41: do_retr: client [6]ika@expressmail.dk(127.0.0.1): sending mes=
sage 328 (3568 bytes)
| 16:44:41: connection_sendresponse: client [6]ika@expressmail.dk(127.0.0=
.1): sent `+OK Message follows'
| 16:44:41: do_retr: client [6]ika@expressmail.dk(127.0.0.1): sent messag=
e 328
| 16:44:41: connection_parsecommand: client [6]ika@expressmail.dk(127.0.0=
.1): received `QUIT'
| 16:44:41: connection_sendresponse: client [6]ika@expressmail.dk(127.0.0=
.1): sent `+OK Done'
| 16:44:41: connections_post_select: client [6]ika@expressmail.dk(127.0.0=
.1): finished session for `ika@expressmail.dk' with mysql+cache
| 16:44:41: connections_post_select: client [6]ika@expressmail.dk(127.0.0=
.1): disconnected; 74/3944 bytes read/written

for one whole session, taking about a second and
downloading one mail. Can you find an example in which
there is a longer delay?

--=20
``We are not without accomplishment. We have managed to distribute
  poverty equally.'' (Nguyen Co Thatch, Vietnamese Foreign Minister)