[tpop3d-discuss] tpop3d, auth-other, php auth script and heavy load

[Ivan Matveyev]

On Tue, 4 Mar 2003, Chris Lightfoot wrote:

> On Tue, Mar 04, 2003 at 03:03:01PM +0300, Ivan Matveyev wrote:
> > If you remember, I use php script for authentication. The problem is that
> > when I start tpop3d on my primary mail server, I encounter a long response
> > timeout. Here goes tpop3d.conf:
> I'm not sure what you mean by `long response timeout'. Do
> you mean for the client, or between tpop3d and the
> authentication program? What log entries do you get?
I mean for the client. Identical configuration works fine with lower load.

Log:

messages:

Mar  4 14:39:26 mail tpop3d[7546]: auth_other_start_child: started
authentication child `/sbin/actions/pop-auth'
Mar  4 14:39:27 mail tpop3d[7546]: got response: 'result'
Mar  4 14:39:27 mail tpop3d[7546]: authcontext_new_user_pass: began
session for `sibhouse' with other; uid 8, gid 12
Mar  4 14:39:27 mail tpop3d[7546]: fork_child:
[14]sibhouse(193.232.119.137): successfully authenticated with other
Mar  4 14:39:27 mail tpop3d[8120]: connections_post_select: client
[14]sibhouse(193.232.119.137): disconnected; 42/142 bytes read/written
Mar  4 14:39:27 mail tpop3d[8120]: authcontext_delete: finished session
for `sibhouse' with other
Mar  4 14:39:32 mail tpop3d[7546]: fork_child: new child is PID 8120
Mar  4 14:39:32 mail tpop3d[7546]: auth_other_start_child: started
authentication child `/sbin/actions/pop-auth'
Mar  4 14:39:32 mail tpop3d[7546]: got response: 'result'
Mar  4 14:39:32 mail tpop3d[7546]: authcontext_new_user_pass: began
session for `khors_ff' with other; uid 8, gid 12
Mar  4 14:39:32 mail tpop3d[7546]: fork_child:
[10]khors_ff(193.232.119.137): successfully authenticated with other
Mar  4 14:39:33 mail tpop3d[8153]: UIDL
Mar  4 14:39:35 mail tpop3d[8153]: connections_post_select: client
[10]khors_ff(193.232.119.137): disconnected; 102/532962 bytes read/written
Mar  4 14:39:35 mail tpop3d[8153]: authconte546]: fork_child: new child is
PID 8153
Mar  4 14:39:38 mail tpop3d[7546]: listeners_post_select: client
[7]212.38.96.226/mail: connected
Mar  4 14:39:38 mail tpop3d[7546]: net_loop: timed out client
[5]213.145.35.116/mail
Mar  4 14:39:38 mail tpop3d[7546]: auth_other_start_child: started
authentication child `/sbin/actions/pop-auth'
Mar  4 14:39:38 mail tpop3d[7546]: got response: 'result'
Mar  4 14:39:38 mail tpop3d[7546]: authcontext_new_user_pass: began
session for `geobgt' with other; uid 8, gid 12
Mar  4 14:39:38 mail tpop3d[7546]: fork_child: [6]geobgt(213.145.40.14):
successfully authenticated with other
Mar  4 14:39:43 mail tpop3d[7546]: fork_child: new child is PID 8160
Mar  4 14:39:43 mail tpop3d[7546]: auth_other_start_child: started
authentication child `/sbin/actions/pop-auth'

syslog:
Mar  4 14:39:08 mail tpop3d[7546]: auth_other_recv_response: read: Bad
file descriptor; killing child
Mar  4 14:39:08 mail tpop3d[7546]: connection_do: client
`[8]213.145.41.146/mail': username `garant': 1 authentication failures
Mar  4 14:39:08 mail tpop3d[7546]: auth_other_recv_response: read: child
closed pipe; killing child
Mar  4 14:39:14 mail tpop3d[7546]: connection_do: client
`[12]195.16.100.122/mail': username `art-mt': 1 authentication failures
Mar  4 14:39:14 mail tpop3d[7546]: auth_other_recv_response: read: Bad
file descriptor; killing child
Mar  4 14:39:15 mail tpop3d[7546]: connection_do: client
`[8]213.145.37.100/mail': username `oooprofit': 1 authentication failures
Mar  4 14:39:16 mail tpop3d[7546]: auth_other_recv_response: read: child
closed pipe; killing child
Mar  4 14:39:38 mail tpop3d[8160]: dotfile_lock(/var/spool/mail/geobgt):
unable to link hitching post to lock file: File exists
Mar  4 14:39:43 mail tpop3d[7546]: connection_do: client
`[8]62.117.82.149/mail': username `korvet': 1 authentication failures
Mar  4 14:39:43 mail tpop3d[7546]: auth_other_recv_response: read: Bad
file descriptor; killing child
Mar  4 14:39:43 mail tpop3d[7546]: connection_do: client
`[11]195.146.83.210/mail': username `adrianmole': 1 authentication
failures
Mar  4 14:39:43 mail tpop3d[7546]: connections_post_select:
connection_read: client [7]212.38.96.226/mail: disconnected: Broken pipe
Mar  4 14:39:43 mail tpop3d[7546]: connections_post_select:
connection_read: client [6]213.145.40.102/mail: disconnected: Broken pipe
Mar  4 14:39:44 mail tpop3d[7546]: connection_do: client
`[7]194.67.23.103/mail': username `yuri_psm@ztel.ru': 1 authentication
failures
Mar  4 14:39:44 mail tpop3d[7546]: auth_other_recv_response: read: Bad
file descriptor; killing child
Mar  4 14:39:44 mail tpop3d[7546]: connection_do: client
`[5]194.67.23.103/mail': username `jorikred': 1 authentication failures


-- 
Ivan Matveyev, Zebra Telecom
Tech Dept, +7(095)741-0001