+------------------------------------------------------------------------------ | On 2008-07-29 20:00:19, Igor Sysoev wrote: | | Could you send debug log to me ?

Just to ensure I'm not being stupid... nginx proxying IMAP-SSL is going to run cleartext to the auth backend, and then cleartext to the actual IMAP daemon, right? I've confirmed (I believe) through tracing the auth and IMAP daemons that they aren't getting any connections from nginx... so I'm not sure where it's hanging, exactly.

Again, thanks for the help!

2008/07/29 12:30:08 [notice] 23220#0: start worker processes 2008/07/29 12:30:08 [debug] 23220#0: channel 8:7 2008/07/29 12:30:08 [notice] 23220#0: start worker process 23221 2008/07/29 12:30:08 [debug] 23220#0: sigsuspend 2008/07/29 12:30:08 [debug] 23221#0: malloc: 0000000000604EB0:256 2008/07/29 12:30:08 [debug] 23221#0: malloc: 0000000000604FC0:256 2008/07/29 12:30:08 [debug] 23221#0: malloc: 00000000006050D0:256 2008/07/29 12:30:08 [debug] 23221#0: malloc: 0000000000625600:8192 2008/07/29 12:30:08 [debug] 23221#0: malloc: 000000000062DF60:40960 2008/07/29 12:30:08 [debug] 23221#0: malloc: 0000000000637F70:26624 2008/07/29 12:30:08 [debug] 23221#0: malloc: 000000000063E780:26624 2008/07/29 12:30:08 [debug] 23221#0: devpoll add event: fd:4 ev:0001 2008/07/29 12:30:08 [debug] 23221#0: devpoll fd:4 ev:0001 fl:0000 2008/07/29 12:30:08 [debug] 23221#0: devpoll add event: fd:5 ev:0001 2008/07/29 12:30:08 [debug] 23221#0: devpoll fd:5 ev:0001 fl:0000 2008/07/29 12:30:08 [debug] 23221#0: devpoll add event: fd:7 ev:0001 2008/07/29 12:30:08 [debug] 23221#0: devpoll fd:7 ev:0001 fl:0000 2008/07/29 12:30:08 [debug] 23221#0: setproctitle: "nginx: worker process
(/opt/local/nginx/sbin/nginx)
" 2008/07/29 12:30:08 [debug] 23221#0: worker cycle 2008/07/29 12:30:08 [debug] 23221#0: devpoll timer: -1 2008/07/29 12:32:39 [debug] 23221#0: devpoll: fd:5, ev:0001, rev:0001 2008/07/29 12:32:39 [debug] 23221#0: accept on 0.0.0.0:993, ready: 1 2008/07/29 12:32:39 [debug] 23221#0: malloc: 0000000000602690:256 2008/07/29 12:32:39 [debug] 23221#0: *1 accept: 127.0.0.1 fd:8 2008/07/29 12:32:39 [debug] 23221#0: malloc: 00000000006027A0:328 2008/07/29 12:32:39 [info] 23221#0: *1 client 127.0.0.1 connected to 0.0.0.0:993 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_do_handshake: -1 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_get_error: 2 2008/07/29 12:32:39 [debug] 23221#0: *1 devpoll add event: fd:8 ev:0001 2008/07/29 12:32:39 [debug] 23221#0: *1 devpoll fd:8 ev:0001 fl:0000 2008/07/29 12:32:39 [debug] 23221#0: *1 event timer add: 8: 60000:1217349219366 2008/07/29 12:32:39 [debug] 23221#0: accept() not ready (11: Resource
temporarily unavailable) 2008/07/29 12:32:39 [debug] 23221#0: timer delta: 150680 2008/07/29 12:32:39 [debug] 23221#0: posted events 0000000000000000 2008/07/29 12:32:39 [debug] 23221#0: worker cycle 2008/07/29 12:32:39 [debug] 23221#0: devpoll timer: 60000 2008/07/29 12:32:39 [debug] 23221#0: devpoll: fd:8, ev:0001, rev:0001 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL handshake handler: 0 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_do_handshake: -1 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_get_error: 2 2008/07/29 12:32:39 [debug] 23221#0: timer delta: 5 2008/07/29 12:32:39 [debug] 23221#0: posted events 0000000000000000 2008/07/29 12:32:39 [debug] 23221#0: worker cycle 2008/07/29 12:32:39 [debug] 23221#0: devpoll timer: 59995 2008/07/29 12:32:39 [debug] 23221#0: devpoll: fd:8, ev:0001, rev:0001 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL handshake handler: 0 2008/07/29 12:32:39 [debug] 23221#0: slab alloc: 119 slot: 4 2008/07/29 12:32:39 [debug] 23221#0: slab alloc: FFFFFD7FFEE50080 2008/07/29 12:32:39 [debug] 23221#0: slab alloc: 128 slot: 4 2008/07/29 12:32:39 [debug] 23221#0: slab alloc: FFFFFD7FFEE50100 2008/07/29 12:32:39 [debug] 23221#0: *1 http ssl new session: 533181D2:32:119 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_do_handshake: 1 2008/07/29 12:32:39 [debug] 23221#0: *1 devpoll del event: fd:8 ev:0001 2008/07/29 12:32:39 [debug] 23221#0: *1 devpoll fd:8 ev:0800 fl:0000 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL: TLSv1, cipher: "DHE-RSA-AES256-SHA
SSLv3 Kx=DH Au=RSA Enc=AES(256) Mac=SHA1" 2008/07/29 12:32:39 [debug] 23221#0: *1 malloc: 0000000000629140:256 2008/07/29 12:32:39 [debug] 23221#0: *1 event timer: 8, old: 1217349219366, new:
1217349219399 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL to write: 18 2008/07/29 12:32:39 [debug] 23221#0: *1 SSL_write: 18 2008/07/29 12:32:39 [debug] 23221#0: timer delta: 28 2008/07/29 12:32:39 [debug] 23221#0: posted events 0000000000000000 2008/07/29 12:32:39 [debug] 23221#0: worker cycle 2008/07/29 12:32:39 [debug] 23221#0: devpoll timer: 59967 2008/07/29 12:33:39 [debug] 23221#0: timer delta: 59971 2008/07/29 12:33:39 [debug] 23221#0: *1 event timer del: 8: 1217349219366 2008/07/29 12:33:39 [info] 23221#0: *1 client timed out (145: Connection timed
out) while in auth state, client: 127.0.0.1, server: 0.0.0.0:993 2008/07/29 12:33:39 [debug] 23221#0: *1 close mail connection: 8 2008/07/29 12:33:39 [debug] 23221#0: *1 SSL_shutdown: 1 2008/07/29 12:33:39 [debug] 23221#0: *1 free: 00000000006027A0 2008/07/29 12:33:39 [debug] 23221#0: *1 free: 0000000000602690, unused: 8 2008/07/29 12:33:39 [debug] 23221#0: *1 free: 0000000000629140, unused: 176 2008/07/29 12:33:39 [debug] 23221#0: posted events 0000000000000000 2008/07/29 12:33:39 [debug] 23221#0: worker cycle 2008/07/29 12:33:39 [debug] 23221#0: devpoll timer: -1