[Dovecot] bug in openssl proxy code (1.0-test24) ?

Andrey Panin pazke at donpac.ru
Fri Jul 9 15:05:58 EEST 2004 

On 191, 07 09, 2004 at 02:46:46PM +0300, Timo Sirainen wrote:
> On Fri, 2004-07-09 at 14:08, Andrey Panin wrote:
> > If client connects to pop3 server, issues STLS command and
> > then drops connection dovecot starts flooding syslog with
> > this message repeated endlessly:
> > 
> > Jul  9 14:31:40 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
> 
> So it seems. Before 1.0 I'll have to check all of my code that uses
> refcounting. That's annoyingly difficult to get right..
 
May be I don't understand something, but it looks like proxy->refcount
simply can't reach zero here. Initial refcount value is 2, each call
to ssl_step() callback increments it and even if ssl_proxy_destroy()
called refcount is stil > 0. I'm confused :(


I inserted some debug i_warning() into ssl-proxy-openssl.c and got this trace:

Jul  9 15:44:52 smtp dovecot: Dovecot v1.0-test24 starting up
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: entered
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: finished
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: entered
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: finished
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: entered
Jul  9 15:44:53 smtp dovecot: pop3-login: ssl_proxy_init: finished
Jul  9 15:45:01 smtp dovecot: pop3-login: ssl_proxy_init: entered
Jul  9 15:45:01 smtp dovecot: pop3-login: ssl_proxy_init: finished
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_proxy_new: entered
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_set_io: entered: proxy = 0x8076218
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_set_io: action = 0 SSL_ADD_INPUT 
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_set_io: finished
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:03 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:12 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:12 smtp dovecot: pop3-login: Sending log messages too fast, throttling..
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:14 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:14 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:15 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:16 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:17 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:18 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:18 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_handle_error: finished
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_handshake: finished
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_proxy_unref: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_step: finished
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_step: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_handshake: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_handle_error: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: SSL_accept() syscall failed: EOF [80.254.111.9]
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_proxy_destroy: entered
Jul  9 15:45:19 smtp dovecot: pop3-login: ssl_proxy_destroy: finished
Jul  9 15:45:20 smtp dovecot: Killed with signal 15

Hope it will help.

-- 
Andrey Panin		| Linux and UNIX system administrator
pazke at donpac.ru		| PGP key: wwwkeys.pgp.net
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 189 bytes
Desc: Digital signature
Url : http://dovecot.org/pipermail/dovecot/attachments/20040709/e399ae73/attachment.pgp

More information about the dovecot mailing list