IMAP/SSL issue.

Bryan Allen bda at mirrorshades.net
Tue Jul 29 20:38:26 MSD 2008


+------------------------------------------------------------------------------
| 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
-- 
bda
Cyberpunk is dead.  Long live cyberpunk.
http://mirrorshades.org





More information about the nginx mailing list