[tpop3d-discuss] Long Login delay

[Ian Armstrong]

Here is a section of my log file just after I logged in.

[root@server.expressmail.dk]# tail -n 50 /var/log/mail
Jan 20 16:44:35 server tpop3d[5928]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `RETR 325'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sending message 325 (2751 bytes)
Jan 20 16:44:35 server tpop3d[5928]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Message follows'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sent message 325
Jan 20 16:44:35 server tpop3d[5928]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `RETR 326'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sending message 326 (3733 bytes)
Jan 20 16:44:35 server tpop3d[5928]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Message follows'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sent message 326
Jan 20 16:44:35 server tpop3d[5928]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `RETR 327'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sending message 327 (3908 bytes)
Jan 20 16:44:35 server tpop3d[5928]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Message follows'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sent message 327
Jan 20 16:44:35 server tpop3d[5928]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `RETR 328'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sending message 328 (3568 bytes)
Jan 20 16:44:35 server tpop3d[5928]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Message follows'
Jan 20 16:44:35 server tpop3d[5928]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sent message 328
Jan 20 16:44:35 server tpop3d[5928]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `QUIT'
Jan 20 16:44:35 server tpop3d[5928]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Done'
Jan 20 16:44:35 server tpop3d[5928]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session for
`ika@expressmail.dk' with mysql
Jan 20 16:44:35 server tpop3d[5928]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 3242/1341542 bytes
read/written
Jan 20 16:44:40 server tpop3d[5924]: connection_sendresponse: client
[6]127.0.0.1/expressmail.dk: sent `+OK
<fddb82f078bf91426003209527b1a298@expressmail.dk>'
Jan 20 16:44:40 server tpop3d[5924]: listeners_post_select: client
[6]127.0.0.1/expressmail.dk: connected to local address 127.0.0.1:110
Jan 20 16:44:40 server tpop3d[5924]: connection_parsecommand: client
[6]127.0.0.1/expressmail.dk: received `CAPA'
Jan 20 16:44:40 server tpop3d[5924]: connection_sendresponse: client
[6]127.0.0.1/expressmail.dk: sent `+OK Capability list follows'
Jan 20 16:44:41 server tpop3d[5924]: connection_parsecommand: client
[6]127.0.0.1/expressmail.dk: received `USER ika@expressmail.dk'
Jan 20 16:44:41 server tpop3d[5924]: connection_sendresponse: client
[6]127.0.0.1/expressmail.dk: sent `+OK Tell me your password.'
Jan 20 16:44:41 server tpop3d[5924]: connection_parsecommand: client
[6]127.0.0.1/expressmail.dk: received `PASS [...]'
Jan 20 16:44:41 server tpop3d[5924]: authcache_new_user_pass: returning
saved entry for [ika@expressmail.dk; ika@expressmail.dk] (19s old) from
slot 72
Jan 20 16:44:41 server tpop3d[5924]: fork_child:
[6]ika@expressmail.dk(127.0.0.1): began session for `ika@expressmail.dk'
with mysql+cache; child PID is 5929
Jan 20 16:44:41 server tpop3d[5924]: auth_mysql_onlogin: SQL query: update
passwd set now = now() where id = 'ika@expressmail.dk'
Jan 20 16:44:41 server tpop3d[5929]: maildir_new: scanned maildir
/home/vmail/expressmail.dk/i/ika/ (328 messages) in 0.005s
Jan 20 16:44:41 server tpop3d[5929]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Welcome aboard! You have 328
messages.'
Jan 20 16:44:41 server tpop3d[5929]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `CAPA'
Jan 20 16:44:41 server tpop3d[5929]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `-ERR Do you actually know how to
use this thing?'
Jan 20 16:44:41 server tpop3d[5929]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `STAT'
Jan 20 16:44:41 server tpop3d[5929]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK 328 1303883'
Jan 20 16:44:41 server tpop3d[5929]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `RETR 328'
Jan 20 16:44:41 server tpop3d[5929]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sending message 328 (3568 bytes)
Jan 20 16:44:41 server tpop3d[5929]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Message follows'
Jan 20 16:44:41 server tpop3d[5929]: do_retr: client
[6]ika@expressmail.dk(127.0.0.1): sent message 328
Jan 20 16:44:41 server tpop3d[5929]: connection_parsecommand: client
[6]ika@expressmail.dk(127.0.0.1): received `QUIT'
Jan 20 16:44:41 server tpop3d[5929]: connection_sendresponse: client
[6]ika@expressmail.dk(127.0.0.1): sent `+OK Done'
Jan 20 16:44:41 server tpop3d[5929]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): finished session for
`ika@expressmail.dk' with mysql+cache
Jan 20 16:44:41 server tpop3d[5929]: connections_post_select: client
[6]ika@expressmail.dk(127.0.0.1): disconnected; 74/3944 bytes
read/written
Jan 20 16:44:54 server postfix/smtpd[5930]: connect from
daleth.esc.cam.ac.uk[131.111.64.59]
Jan 20 16:44:54 server postfix/smtpd[5930]: A55A656ABF:
client=daleth.esc.cam.ac.uk[131.111.64.59]
Jan 20 16:44:54 server postfix/cleanup[5931]: A55A656ABF:
message-id=<YmvecrwLTM2q.RjNqZUDi3t7QINW7CfPmoA@caesious.cold.local>
Jan 20 16:44:54 server postfix/qmgr[312]: A55A656ABF:
from=<chris@ex-parrot.com>, size=3163, nrcpt=1 (queue active)
Jan 20 16:44:54 server postfix/virtual[5934]: A55A656ABF:
to=<ika@expressmail.dk>, relay=virtual, delay=0, status=sent (maildir)
Jan 20 16:44:54 server postfix/smtpd[5930]: disconnect from
daleth.esc.cam.ac.uk[131.111.64.59]






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