проблемы с ssl backend

Sergey Petrov sergey.siroezhkin at gmail.com
Wed Jul 1 04:37:25 MSD 2009


nginx работает как фронтенд для HTTPS сервера(Tomcat), возникла
проблема коммуникации с бэкэндом.
конфиг:

location / {
    proxy_pass https://XXX.XXX.XXX.XXX:443;
}


первый запрос проходит нормально, все остальные возвращают 502.
пробовал обновить OS(Linux FC10-FC11), openssl(0.9.8g до 0.9.8k), не помогло,
при этом не возникло проблем под FreeBSD на подобном конфиге;
возможно что-то не так в обработчике epoll, хотя на CentOS проблему
воспроизвести также не удалось.

Пожалуйста, помогите локализовать и устранить проблему?
Спасибо.

лог 2 последовательных запросов в режиме debug прилагаю.
-------------- next part --------------
2009/06/30 22:34:25 [debug] 6737#0: *4 http cl:-1 max:1048576
2009/06/30 22:34:25 [debug] 6737#0: *4 generic phase: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post rewrite phase: 3
2009/06/30 22:34:25 [debug] 6737#0: *4 generic phase: 4
2009/06/30 22:34:25 [debug] 6737#0: *4 access phase: 5
2009/06/30 22:34:25 [debug] 6737#0: *4 access phase: 6
2009/06/30 22:34:25 [debug] 6737#0: *4 access phase: 7
2009/06/30 22:34:25 [debug] 6737#0: *4 post access phase: 8
2009/06/30 22:34:25 [debug] 6737#0: *4 http init upstream, client timer: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 epoll add event: fd:31 op:3 ev:80000005
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header:
"GET / HTTP/1.0
Host: secure.domainyyy.com
X-Real-IP: XXX.X.XX.XXX
X-Forwarded-For: XXX.X.XX.XXX
Connection: close
Cookie: SESSION=1234567

"
2009/06/30 22:34:25 [debug] 6737#0: *4 http cleanup add: 09F3554C
2009/06/30 22:34:25 [debug] 6737#0: *4 get rr peer, try: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 socket 32
2009/06/30 22:34:25 [debug] 6737#0: *4 epoll add connection: fd:32 ev:80000005
2009/06/30 22:34:25 [debug] 6737#0: *4 connect to XXX.XXX.XX.XXX:443, fd:32 #7
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream connect: -2
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer add: 32: 60000:875209811
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA010C
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA010C
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream check client, write event:1, "/"
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream recv(): -1 (11: Resource temporarily unavailable)
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream send request handler
2009/06/30 22:34:25 [debug] 6737#0: *4 set session: 00000000:0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL handshake handler: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_do_handshake: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL: TLSv1, cipher: "EDH-RSA-DES-CBC3-SHA SSLv3 Kx=DH Au=RSA Enc=3DES(168) Mac=SHA1"
2009/06/30 22:34:25 [debug] 6737#0: *4 save session: 09F36078:2
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream send request
2009/06/30 22:34:25 [debug] 6737#0: *4 chain writer buf fl:1 s:146
2009/06/30 22:34:25 [debug] 6737#0: *4 chain writer in: 09F35584
2009/06/30 22:34:25 [debug] 6737#0: *4 malloc: 09E71150:16384
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL buf copy: 146
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL to write: 146
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_write: 146
2009/06/30 22:34:25 [debug] 6737#0: *4 chain writer out: 00000000
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer del: 32: 875209811
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer add: 32: 60000:875209939
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process header
2009/06/30 22:34:25 [debug] 6737#0: *4 malloc: 09F7EEE8:4096
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_read: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process header
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_read: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream send request handler
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream send request
2009/06/30 22:34:25 [debug] 6737#0: *4 chain writer in: 00000000
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer: 32, old: 875209939, new: 875209965
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process header
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_read: 428
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_read: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 2
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy status 200 "200 OK"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Server: Apache-Coyote/1.1"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "X-Powered-By: Servlet 2.4; JBoss-4.2.2.GA (build: SVNTag=JBoss_4_2_2_GA date=200710221139)/Tomcat-5.5"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "ETag: W/"102-1243882460000""
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Last-Modified: Mon, 01 Jun 2009 18:54:20 GMT"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Content-Type: text/html"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Content-Length: 102"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Date: Tue, 30 Jun 2009 23:38:05 GMT"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header: "Connection: close"
2009/06/30 22:34:25 [debug] 6737#0: *4 http proxy header done
2009/06/30 22:34:25 [debug] 6737#0: *4 HTTP/1.1 200 OK
Server: nginx
Date: Wed, 01 Jul 2009 02:34:25 GMT
Content-Type: text/html
Connection: close
X-Powered-By: Servlet 2.4; JBoss-4.2.2.GA (build: SVNTag=JBoss_4_2_2_GA date=200710221139)/Tomcat-5.5
ETag: W/"102-1243882460000"
Last-Modified: Mon, 01 Jun 2009 18:54:20 GMT
Content-Length: 102


2009/06/30 22:34:25 [debug] 6737#0: *4 write new buf t:1 f:0 09F35838, pos 09F35838, size: 314 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 http write filter: l:0 f:0 s:314
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process upstream
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe read upstream: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe preread: 102
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe buf free s:0 t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write downstream: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write busy: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write: out:00000000, f:0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe read upstream: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe buf free s:0 t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer: 32, old: 875209939, new: 875209965
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process upstream
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe read upstream: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe buf free s:0 t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer: 32, old: 875209939, new: 875209965
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 post event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B5AA0140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream send request handler
2009/06/30 22:34:25 [debug] 6737#0: *4 delete posted event B61F8140
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream process upstream
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe read upstream: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_read: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_get_error: 6
2009/06/30 22:34:25 [debug] 6737#0: *4 peer shutdown SSL cleanly
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe recv chain: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe buf free s:0 t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 input buf #0
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write downstream: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write downstream flush in
2009/06/30 22:34:25 [debug] 6737#0: *4 http output filter "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 copy filter: "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 http postpone filter "/?" 09F35A58
2009/06/30 22:34:25 [debug] 6737#0: *4 http postpone filter out "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 write old buf t:1 f:0 09F35838, pos 09F35838, size: 314 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 write new buf t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 http write filter: l:0 f:0 s:416
2009/06/30 22:34:25 [debug] 6737#0: *4 copy filter: 0 "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 pipe write downstream done
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer: 32, old: 875209939, new: 875209965
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream exit: 00000000
2009/06/30 22:34:25 [debug] 6737#0: *4 finalize http upstream request: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 finalize http proxy request
2009/06/30 22:34:25 [debug] 6737#0: *4 free rr peer 1 0
2009/06/30 22:34:25 [debug] 6737#0: *4 SSL_shutdown: 1
2009/06/30 22:34:25 [debug] 6737#0: *4 close http upstream connection: 32
2009/06/30 22:34:25 [debug] 6737#0: *4 event timer del: 32: 875209939
2009/06/30 22:34:25 [debug] 6737#0: *4 http upstream temp fd: -1
2009/06/30 22:34:25 [debug] 6737#0: *4 http output filter "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 copy filter: "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 http postpone filter "/?" BFC60338
2009/06/30 22:34:25 [debug] 6737#0: *4 http postpone filter out "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 write old buf t:1 f:0 09F35838, pos 09F35838, size: 314 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 write old buf t:1 f:0 09F7EEE8, pos 09F7F02E, size: 102 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0
2009/06/30 22:34:25 [debug] 6737#0: *4 http write filter: l:1 f:0 s:416
2009/06/30 22:34:25 [debug] 6737#0: *4 http write filter limit 0
2009/06/30 22:34:25 [debug] 6737#0: *4 writev: 416
2009/06/30 22:34:25 [debug] 6737#0: *4 http write filter 00000000
2009/06/30 22:34:25 [debug] 6737#0: *4 copy filter: 0 "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 http finalize request: 0, "/?"
2009/06/30 22:34:25 [debug] 6737#0: *4 http close request
2009/06/30 22:34:25 [debug] 6737#0: *4 http log handler
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09F7EEE8
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09E71150
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09F34CE8, unused: 524
2009/06/30 22:34:25 [debug] 6737#0: *4 close http connection: 31
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09F348E0
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09F34658
2009/06/30 22:34:25 [debug] 6737#0: *4 free: 09E75EA0, unused: 64
2009/06/30 22:34:28 [debug] 6737#0: *8 http cl:-1 max:1048576
2009/06/30 22:34:28 [debug] 6737#0: *8 generic phase: 2
2009/06/30 22:34:28 [debug] 6737#0: *8 post rewrite phase: 3
2009/06/30 22:34:28 [debug] 6737#0: *8 generic phase: 4
2009/06/30 22:34:28 [debug] 6737#0: *8 access phase: 5
2009/06/30 22:34:28 [debug] 6737#0: *8 access phase: 6
2009/06/30 22:34:28 [debug] 6737#0: *8 access phase: 7
2009/06/30 22:34:28 [debug] 6737#0: *8 post access phase: 8
2009/06/30 22:34:28 [debug] 6737#0: *8 http init upstream, client timer: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 epoll add event: fd:31 op:3 ev:80000005
2009/06/30 22:34:28 [debug] 6737#0: *8 http proxy header:
"GET / HTTP/1.0
Host: secure.domainyyy.com
X-Real-IP: XXX.X.XX.XXX
X-Forwarded-For: XXX.X.XX.XXX
Connection: close
Cookie: SESSION=1234567

"
2009/06/30 22:34:28 [debug] 6737#0: *8 http cleanup add: 09F34EBC
2009/06/30 22:34:28 [debug] 6737#0: *8 get rr peer, try: 1
2009/06/30 22:34:28 [debug] 6737#0: *8 socket 32
2009/06/30 22:34:28 [debug] 6737#0: *8 epoll add connection: fd:32 ev:80000005
2009/06/30 22:34:28 [debug] 6737#0: *8 connect to XXX.XXX.XX.XXX:443, fd:32 #9
2009/06/30 22:34:28 [debug] 6737#0: *8 http upstream connect: -2
2009/06/30 22:34:28 [debug] 6737#0: *8 event timer add: 32: 60000:875212352
2009/06/30 22:34:28 [debug] 6737#0: *8 post event B5AA010C
2009/06/30 22:34:28 [debug] 6737#0: *8 delete posted event B5AA010C
2009/06/30 22:34:28 [debug] 6737#0: *8 http upstream check client, write event:1, "/"
2009/06/30 22:34:28 [debug] 6737#0: *8 http upstream recv(): -1 (11: Resource temporarily unavailable)
2009/06/30 22:34:28 [debug] 6737#0: *8 post event B5AA0140
2009/06/30 22:34:28 [debug] 6737#0: *8 delete posted event B5AA0140
2009/06/30 22:34:28 [debug] 6737#0: *8 http upstream send request handler
2009/06/30 22:34:28 [debug] 6737#0: *8 set session: 09F36078:2
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL_do_handshake: -1
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL_get_error: 2
2009/06/30 22:34:28 [debug] 6737#0: *8 post event B61F8140
2009/06/30 22:34:28 [debug] 6737#0: *8 post event B5AA0140
2009/06/30 22:34:28 [debug] 6737#0: *8 delete posted event B5AA0140
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL handshake handler: 1
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL_do_handshake: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL_get_error: 1
2009/06/30 22:34:28 [error] 6737#0: *8 SSL_do_handshake() failed (SSL: error:140943F2:SSL routines:SSL3_READ_BYTES:sslv3 alert unexpected message) while SSL handshaking to upstream, client: XXX.X.XX.XXX, server: domainyyy.com, request: "GET / HTTP/1.0", upstream: "https://XXX.XXX.XX.XXX:443/", host: "secure.domainyyy.com"
2009/06/30 22:34:28 [debug] 6737#0: *8 http next upstream, 2
2009/06/30 22:34:28 [debug] 6737#0: *8 free rr peer 1 4
2009/06/30 22:34:28 [debug] 6737#0: *8 finalize http upstream request: 502
2009/06/30 22:34:28 [debug] 6737#0: *8 finalize http proxy request
2009/06/30 22:34:28 [debug] 6737#0: *8 free rr peer 0 0
2009/06/30 22:34:28 [debug] 6737#0: *8 SSL_shutdown: 1
2009/06/30 22:34:28 [debug] 6737#0: *8 close http upstream connection: 32
2009/06/30 22:34:28 [debug] 6737#0: *8 event timer del: 32: 875212352
2009/06/30 22:34:28 [debug] 6737#0: *8 delete posted event B61F8140
2009/06/30 22:34:28 [debug] 6737#0: *8 http finalize request: 502, "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 http special response: 502, "/"
2009/06/30 22:34:28 [debug] 6737#0: *8 http set discard body
2009/06/30 22:34:28 [debug] 6737#0: *8 HTTP/1.1 502 Bad Gateway
Server: nginx
Date: Wed, 01 Jul 2009 02:34:28 GMT
Content-Type: text/html
Content-Length: 166
Connection: close


2009/06/30 22:34:28 [debug] 6737#0: *8 write new buf t:1 f:0 09F34F28, pos 09F34F28, size: 145 file: 0, size: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 http write filter: l:0 f:0 s:145
2009/06/30 22:34:28 [debug] 6737#0: *8 http output filter "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 copy filter: "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 http postpone filter "/?" 09F35074
2009/06/30 22:34:28 [debug] 6737#0: *8 http postpone filter out "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 write old buf t:1 f:0 09F34F28, pos 09F34F28, size: 145 file: 0, size: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 write new buf t:0 f:0 00000000, pos 080B2E20, size: 120 file: 0, size: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 write new buf t:0 f:0 00000000, pos 080B1E40, size: 46 file: 0, size: 0
2009/06/30 22:34:28 [debug] 6737#0: *8 http write filter: l:1 f:0 s:311
2009/06/30 22:34:28 [debug] 6737#0: *8 http write filter limit 0
2009/06/30 22:34:28 [debug] 6737#0: *8 writev: 311
2009/06/30 22:34:28 [debug] 6737#0: *8 http write filter 00000000
2009/06/30 22:34:28 [debug] 6737#0: *8 copy filter: 0 "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 http finalize request: 0, "/?"
2009/06/30 22:34:28 [debug] 6737#0: *8 http close request
2009/06/30 22:34:28 [debug] 6737#0: *8 http log handler
2009/06/30 22:34:28 [debug] 6737#0: *8 free: 09F34658, unused: 1476
2009/06/30 22:34:28 [debug] 6737#0: *8 close http connection: 31
2009/06/30 22:34:28 [debug] 6737#0: *8 free: 09F75FA0
2009/06/30 22:34:28 [debug] 6737#0: *8 free: 09F755C8
2009/06/30 22:34:28 [debug] 6737#0: *8 free: 09E75EA0, unused: 64


More information about the nginx-ru mailing list