Proxy, SSL

[mFF <mff@xxxxx>]

Настройки nginx
worker_processes  1;


events {
    worker_connections  1024;
}


http {
    include       mime.types;
    default_type  application/octet-stream;

    sendfile      on;
    tcp_nodelay   on;

    keepalive_timeout  0;

    gzip  off;

    proxy_buffering off;
    proxy_read_timeout    120;
    fastcgi_read_timeout  2m;

server {
     listen 443;
     server_name bug.somedomain.ru;

     ssl on;
     ssl_certificate /usr/local/etc/nginx/ssl/bug.crt;
     ssl_certificate_key /usr/local/etc/nginx/ssl/bug.key;
     ssl_ciphers 
ALL:!ADH:!EXPORT56:RC4+RSA:+HIGH:+MEDIUM:+LOW:+SSLv2:+EXP:+eNULL;

     keepalive_timeout 70;

     access_log /var/log/nginx/bug-access.log;
     error_log /var/log/nginx/bug-error.log debug;

     location / {
     proxy_pass https://bug.somedomain.ru:8282/;
     proxy_redirect default;
        }
    }
}

На https://bug.somedomain.ru:8282/ находится страница с формой для

авторизации, она отображается нормально. После ввода логина/пароля и 
нажатия кнопки получаем сообщение 504 Gateway Time-out.
В чем причина не понятно.

Отладочный лог в аттаче.

Заранее спасибо за ответ :)

2006/02/06 17:48:42 [debug] 42999#0: kevent set event: 15: ft:-1 fl:0005
2006/02/06 17:49:28 [debug] 42999#0: accept on 195.218.239.38:443, ready: 1
2006/02/06 17:49:28 [debug] 42999#0: malloc: 080C3300:256
2006/02/06 17:49:28 [debug] 42999#0: accept: fd:26 c:6
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer add: 26: 60000:1071094960
2006/02/06 17:49:28 [debug] 42999#0: *6 kevent set event: 26: ft:-1 fl:0025
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080B2C00:604
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080C8000:16384
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080C3400:256
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080CD400:1024
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080CE000:4096
2006/02/06 17:49:28 [debug] 42999#0: *6 http check ssl handshake
2006/02/06 17:49:28 [debug] 42999#0: *6 https ssl handshake: 0x16
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL handshake handler: 0
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: 1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL: SSLv3, cipher: "RC4-MD5 SSLv3 
Kx=RSA Au=RSA Enc=RC4(128) Mac=MD5"
2006/02/06 17:49:28 [debug] 42999#0: *6 http process request line
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_read: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer: 26, old: 1071094960, new: 
1071095013
2006/02/06 17:49:28 [debug] 42999#0: *6 http process request line
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_read: 501
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_read: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 http request line: "POST /index.cgi 
HTTP/1.1"
2006/02/06 17:49:28 [debug] 42999#0: *6 http uri: "/index.cgi"
2006/02/06 17:49:28 [debug] 42999#0: *6 http args: ""
2006/02/06 17:49:28 [debug] 42999#0: *6 http exten: "cgi"
2006/02/06 17:49:28 [debug] 42999#0: *6 http process request header line
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Accept: image/gif, 
image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, */*"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Referer: 
https://bug.digitalxpert.ru/";
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Accept-Language: ru"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Content-Type: 
application/x-www-form-urlencoded"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Accept-Encoding: gzip, 
deflate"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "User-Agent: Mozilla/4.0 
(compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 1.1.4322)"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Host: bug.digitalxpert.ru"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Content-Length: 56"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Connection: Keep-Alive"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header: "Cache-Control: no-cache"
2006/02/06 17:49:28 [debug] 42999#0: *6 http header done
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer del: 26: 1071094960
2006/02/06 17:49:28 [debug] 42999#0: *6 http phase handler
2006/02/06 17:49:28 [debug] 42999#0: *6 find location for "/index.cgi"
2006/02/06 17:49:28 [debug] 42999#0: *6 find location: "/"
2006/02/06 17:49:28 [debug] 42999#0: *6 using configuration "/"
2006/02/06 17:49:28 [debug] 42999#0: *6 http cl:56 max:1048576
2006/02/06 17:49:28 [debug] 42999#0: *6 http init upstream, client timer: 0
2006/02/06 17:49:28 [debug] 42999#0: *6 kevent set event: 26: ft:-2 fl:0025
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Accept: image/gif, 
image/x-xbitmap, image/jpeg, image/pjpeg, application/x-shockwave-flash, */*"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Referer: 
https://bug.digitalxpert.ru/";
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Accept-Language: ru"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Content-Type: 
application/x-www-form-urlencoded"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Accept-Encoding: 
gzip, deflate"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "User-Agent: 
Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 1.1.4322)"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Content-Length: 56"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header: "Cache-Control: 
no-cache"
2006/02/06 17:49:28 [debug] 42999#0: *6 http proxy header:
"POST /index.cgi HTTP/1.0
Host: bug.digitalxpert.ru:8282
Connection: close
Accept: image/gif, image/x-xbitmap, image/jpeg, image/pjpeg, 
application/x-shockwave-flash, */*
Referer: https://bug.digitalxpert.ru/
Accept-Language: ru
Content-Type: application/x-www-form-urlencoded
Accept-Encoding: gzip, deflate
User-Agent: Mozilla/4.0 (compatible; MSIE 6.0; Windows NT 5.0; .NET CLR 
1.1.4322)
Content-Length: 56
Cache-Control: no-cache

"
2006/02/06 17:49:28 [debug] 42999#0: *6 http cleanup add: 080CE838
2006/02/06 17:49:28 [debug] 42999#0: *6 socket 27
2006/02/06 17:49:28 [debug] 42999#0: *6 connect to 192.168.0.5:8282, fd:27 #7
2006/02/06 17:49:28 [debug] 42999#0: *6 kevent set event: 27: ft:-1 fl:0025
2006/02/06 17:49:28 [debug] 42999#0: *6 kevent set event: 27: ft:-2 fl:0025
2006/02/06 17:49:28 [debug] 42999#0: *6 http upstream connect: -2
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer add: 27: 60000:1071095089
2006/02/06 17:49:28 [debug] 42999#0: *6 http upstream check client, write 
event:1, "/index.cgi"
2006/02/06 17:49:28 [debug] 42999#0: *6 http upstream send request handler
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 0810A000:16384
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL handshake handler: 1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL handshake handler: 0
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL handshake handler: 1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_do_handshake: 1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL: TLSv1, cipher: "DHE-RSA-AES256-SHA 
SSLv3 Kx=DH Au=RSA Enc=AES(256) Mac=SHA1"
2006/02/06 17:49:28 [debug] 42999#0: *6 http upstream send request
2006/02/06 17:49:28 [debug] 42999#0: *6 chain writer buf size: 445
2006/02/06 17:49:28 [debug] 42999#0: *6 chain writer buf size: 56
2006/02/06 17:49:28 [debug] 42999#0: *6 chain writer in: 080CE8AC
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL buf copy: 445
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL buf copy: 56
2006/02/06 17:49:28 [debug] 42999#0: *6 chain writer out: 00000000
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer del: 27: 1071095089
2006/02/06 17:49:28 [debug] 42999#0: *6 event timer add: 27: 120000:1071155263
2006/02/06 17:49:28 [debug] 42999#0: *6 http upstream process header
2006/02/06 17:49:28 [debug] 42999#0: *6 malloc: 080D0000:4096
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_read: -1
2006/02/06 17:49:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:51:28 [debug] 42999#0: *6 event timer del: 27: 1071155263
2006/02/06 17:51:28 [debug] 42999#0: *6 http upstream process header
2006/02/06 17:51:28 [debug] 42999#0: *6 http next upstream, 4
2006/02/06 17:51:28 [error] 42999#0: *6 upstream timed out (60: Operation timed 
out) while reading response header from upstream, client: 195.144.244.234, 
server: bug.digitalxpert.ru, URL: "/index.cgi", upstream: 
"https://192.168.0.5:8282/index.cgi";, host: "bug.digitalxpert.ru", referrer: 
"https://bug.digitalxpert.ru/";
2006/02/06 17:51:28 [debug] 42999#0: *6 finalize http upstream request: 504
2006/02/06 17:51:28 [debug] 42999#0: *6 finalize http proxy request
2006/02/06 17:51:28 [debug] 42999#0: *6 close http upstream connection: 27
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL_shutdown: 1
2006/02/06 17:51:28 [debug] 42999#0: *6 http finalize request: 504, 
"/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 http special response: 504, "/index.cgi"
2006/02/06 17:51:28 [debug] 42999#0: *6 http set discard body
2006/02/06 17:51:28 [debug] 42999#0: *6 http read discarded body
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL_read: -1
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL_get_error: 2
2006/02/06 17:51:28 [debug] 42999#0: *6 HTTP/1.1 504 Gateway Time-out
Server: nginx/0.3.26
Date: Mon, 06 Feb 2006 14:51:28 GMT
Content-Type: text/html
Content-Length: 543
Connection: close


2006/02/06 17:51:28 [debug] 42999#0: *6 write new buf t:1 f:0 080CE998, pos 
080CE998, size: 157 file: 0, size: 0
2006/02/06 17:51:28 [debug] 42999#0: *6 http write filter: l:0 f:0 s:157
2006/02/06 17:51:28 [debug] 42999#0: *6 http output filter "/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 copy filter: "/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 http postpone filter "/index.cgi?" 
080CEB3C
2006/02/06 17:51:28 [debug] 42999#0: *6 http postpone filter out "/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 write old buf t:1 f:0 080CE998, pos 
080CE998, size: 157 file: 0, size: 0
2006/02/06 17:51:28 [debug] 42999#0: *6 write new buf t:0 f:0 00000000, pos 
0808E900, size: 130 file: 0, size: 0
2006/02/06 17:51:28 [debug] 42999#0: *6 write new buf t:0 f:0 00000000, pos 
0808DB40, size: 53 file: 0, size: 0
2006/02/06 17:51:28 [debug] 42999#0: *6 write new buf t:0 f:0 00000000, pos 
0808DB80, size: 360 file: 0, size: 0
2006/02/06 17:51:28 [debug] 42999#0: *6 http write filter: l:1 f:0 s:700
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL buf copy: 157
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL buf copy: 130
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL buf copy: 53
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL buf copy: 360
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL to write: 700
2006/02/06 17:51:28 [debug] 42999#0: *6 SSL_write: 700
2006/02/06 17:51:28 [debug] 42999#0: *6 http write filter 00000000
2006/02/06 17:51:28 [debug] 42999#0: *6 copy filter: 0 "/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 http finalize request: 0, "/index.cgi?"
2006/02/06 17:51:28 [debug] 42999#0: *6 event timer add: 26: 5000:1071160277
2006/02/06 17:51:28 [debug] 42999#0: *6 http empty handler
2006/02/06 17:51:29 [debug] 42999#0: *6 http empty handler
2006/02/06 17:51:29 [debug] 42999#0: *6 http lingering close handler
2006/02/06 17:51:29 [debug] 42999#0: *6 SSL_read: 0
2006/02/06 17:51:29 [debug] 42999#0: *6 SSL_get_error: 5
2006/02/06 17:51:29 [debug] 42999#0: *6 peer shutdown SSL cleanly
2006/02/06 17:51:29 [debug] 42999#0: *6 lingering read: 0
2006/02/06 17:51:29 [debug] 42999#0: *6 http close request
2006/02/06 17:51:29 [debug] 42999#0: *6 http log handler
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 0810A000
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080D0000
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080CE000, unused: 968
2006/02/06 17:51:29 [debug] 42999#0: *6 close http connection: 26
2006/02/06 17:51:29 [debug] 42999#0: *6 SSL_shutdown: 1
2006/02/06 17:51:29 [debug] 42999#0: *6 event timer del: 26: 1071160277
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080B2C00
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080C8000
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080CD400
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080C3300, unused: 32
2006/02/06 17:51:29 [debug] 42999#0: *6 free: 080C3400, unused: 172