[tpop3d-discuss] long login delay

[Ian Armstrong]

On 06-Jan-2004, Chris Lightfoot <chris@ex-parrot.com> wrote: 

|which version? how heavily loaded is the server? anything
|interesting in the logs?
|
|-- 
|``You can't say that, because it's true.''
|  (unnamed Russian censor, to Malcom Muggeridge, 1933)

I am using the latest version "tpop3d-1.5.3". My server does not have a
heavy load, I only have a few active users. The 
logs seem to be normal. Here is a sample just after I logged in:

bash-2.05b# tail -n 20 /var/log/mail
Jan  6 12:38:06 server tpop3d[5939]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session 
for `ika@expressmail.dk' with mysql
Jan  6 12:38:06 server tpop3d[5939]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 
889/1581385 bytes read/written
Jan  6 12:38:33 server tpop3d[5936]: listeners_post_select: client
[6]127.0.0.1/expressmail.dk: connected to local 
address 127.0.0.1:110
Jan  6 12:38:33 server tpop3d[5936]: authcontext_new_user_pass: began
session for `ika@expressmail.dk' with mysql; uid 
8, gid 12
Jan  6 12:38:33 server tpop3d[5936]: fork_child:
[6]ika@expressmail.dk(127.0.0.1): began session for `ika@expressmail.
dk' with mysql; child PID is 5940
Jan  6 12:38:33 server tpop3d[5940]: maildir_new: scanned maildir
/home/vmail/expressmail.dk/i/ika/ (92 messages) in 0.
002s
Jan  6 12:38:34 server tpop3d[5940]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session 
for `ika@expressmail.dk' with mysql
Jan  6 12:38:34 server tpop3d[5940]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 
73/4871 bytes read/written
Jan  6 12:39:15 server tpop3d[5936]: listeners_post_select: client
[6]127.0.0.1/expressmail.dk: connected to local 
address 127.0.0.1:110
Jan  6 12:39:15 server tpop3d[5936]: authcontext_new_user_pass: began
session for `ika@expressmail.dk' with mysql; uid 
8, gid 12
Jan  6 12:39:15 server tpop3d[5936]: fork_child:
[6]ika@expressmail.dk(127.0.0.1): began session for `ika@expressmail.
dk' with mysql; child PID is 5941
Jan  6 12:39:15 server tpop3d[5941]: maildir_new: scanned maildir
/home/vmail/expressmail.dk/i/ika/ (92 messages) in 0.
002s
Jan  6 12:39:15 server tpop3d[5941]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session 
for `ika@expressmail.dk' with mysql
Jan  6 12:39:15 server tpop3d[5941]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 
73/3313 bytes read/written
Jan  6 12:41:48 server tpop3d[5936]: listeners_post_select: client
[6]127.0.0.1/expressmail.dk: connected to local 
address 127.0.0.1:110
Jan  6 12:41:48 server tpop3d[5936]: authcontext_new_user_pass: began
session for `ika@expressmail.dk' with mysql; uid 
8, gid 12
Jan  6 12:41:48 server tpop3d[5936]: fork_child:
[6]ika@expressmail.dk(127.0.0.1): began session for `ika@expressmail.
dk' with mysql; child PID is 5942
Jan  6 12:41:48 server tpop3d[5942]: maildir_new: scanned maildir
/home/vmail/expressmail.dk/i/ika/ (92 messages) in 0.
002s
Jan  6 12:41:48 server tpop3d[5942]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session 
for `ika@expressmail.dk' with mysql
Jan  6 12:41:48 server tpop3d[5942]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 
73/3313 bytes read/written

Until now I have been using Courier which uses a filesystem pipe to
connect to mysql:"MYSQL_SOCKET /tmp/mysql.sock". The 
only thing I can think of, is that the delay lies in my network setup.
Instead of the pipe, tpop3d is using TCP, and 
this is causing the delay. I am using a webmail client, and the reason I
think this, is that I get the "waiting" message 
when I try to log in: "waiting for www.expressmail.dk". 


Ian Armstrong
----------------------------------
ika@expressmail.dk