не работает proxy_cache

[Евгений Торопов]

Добрый день,

Не могу понять, что мешает кэшированию.

Кэш-конфиг:

proxy_cache_path /opt/local/nginx/cache levels=1:2 keys_zone=sup:32m max_size=1024m inactive=1h;

proxy_cache                 sup;
proxy_cache_valid           1h;

proxy_cache_key            "$host$request_uri";
proxy_cache_methods         POST;



Отрывок дебаг лога:

....................................................................................................................................
2011/07/20 12:02:16 [debug] 99556#0: *15 http cache key: "hot/xml/listen_xml.jsp?md5=f092037f20486505cc4e20ff08254d3c"
2011/07/20 12:02:16 [debug] 99556#0: *15 add cleanup: 00000001008049E0
2011/07/20 12:02:16 [debug] 99556#0: slab alloc: 112 slot: 4
2011/07/20 12:02:16 [debug] 99556#0: slab alloc: 0000000101030080
2011/07/20 12:02:16 [debug] 99556#0: *15 http file cache exists: -5 e:0
2011/07/20 12:02:16 [debug] 99556#0: *15 cache file: "/opt/local/nginx/cache/4/8a/26028e075ed8e2149fcfaa2ecd1338a4"
2011/07/20 12:02:16 [debug] 99556#0: *15 http upstream cache: -5
2011/07/20 12:02:16 [debug] 99556#0: *15 http script copy: "Host: "
2011/07/20 12:02:16 [debug] 99556#0: *15 http script var: "xxxxxxxxxxxxxx"
2011/07/20 12:02:16 [debug] 99556#0: *15 http script copy: "
"
2011/07/20 12:02:16 [debug] 99556#0: *15 http script copy: "Connection: close
"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "User-agent: xxxxxxxxxx"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "Content-type: application/x-www-form-urlencoded"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "Te: trailers"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "Accept-encoding: deflate;"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "Content-length: 1151"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header: "Referer: http://hot:8080/xml/listen_xml.jsp?md5=f092037f20486505cc4e20ff08254d3c"
2011/07/20 12:02:16 [debug] 99556#0: *15 http proxy header:
"POST /xml/listen_xml.jsp?md5=f092037f20486505cc4e20ff08254d3c HTTP/1.0
Host: xxxxxxxxxxxx
Connection: close
User-agent: xxxxxxxxx
Content-type: application/x-www-form-urlencoded
Te: trailers
Accept-encoding: deflate;
Content-length: 1151
Referer: http://hot:8080/xml/listen_xml.jsp?md5=f092037f20486505cc4e20ff08254d3c

"
2011/07/20 12:02:16 [debug] 99556#0: *15 http cleanup add: 000000010082ACF8
....................................................................................................................................
....................................................................................................................................
2011/07/20 12:02:16 [debug] 99557#0: http file cache expire
2011/07/20 12:02:16 [debug] 99557#0: malloc: 0000000100214EA0:61
2011/07/20 12:02:16 [debug] 99557#0: http file cache size: 0
2011/07/20 12:02:16 [debug] 99557#0: event timer add: -1: 10000:1311148946938
2011/07/20 12:02:16 [debug] 99557#0: posted events 0000000000000000
2011/07/20 12:02:16 [debug] 99557#0: kevent timer: 10000, changes: 0
2011/07/20 12:02:20 [debug] 99556#0: kevent events: 1
2011/07/20 12:02:20 [debug] 99556#0: kevent: 13: ft:-1 fl:0025 ff:00000000 d:1368 ud:000000010083CF50
2011/07/20 12:02:20 [debug] 99556#0: *15 http upstream request: "/xml/listen_xml.jsp?md5=f092037f20486505cc4e20ff08254d3c"
2011/07/20 12:02:20 [debug] 99556#0: *15 http upstream process header
2011/07/20 12:02:20 [debug] 99556#0: *15 malloc: 0000000100810200:4096
2011/07/20 12:02:20 [debug] 99556#0: *15 recv: eof:0, avail:1368, err:0
2011/07/20 12:02:20 [debug] 99556#0: *15 recv: fd:13 1368 of 3990
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy status 200 "200 OK"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Date: Wed, 20 Jul 2011 08:02:16 GMT"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Server: Apache/2.0.52 (Red Hat)"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Content-Encoding: deflate"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Vary: Accept-Encoding"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Set-Cookie: JSESSIONID=avIghzWKEQxa; path=/"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Connection: close"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header: "Content-Type: text/xml; charset=ISO-8859-1"
2011/07/20 12:02:20 [debug] 99556#0: *15 http proxy header done
2011/07/20 12:02:20 [debug] 99556#0: *15 HTTP/1.1 200 OK
Server: nginx/0.8.54
Date: Wed, 20 Jul 2011 08:02:20 GMT
Content-Type: text/xml; charset=ISO-8859-1
Transfer-Encoding: chunked
Connection: close
Content-Encoding: deflate
Vary: Accept-Encoding
Set-Cookie: JSESSIONID=avIghzWKEQxa; path=/

2011/07/20 12:02:20 [debug] 99556#0: *15 write new buf t:1 f:0 000000010082B088, pos 000000010082B088, size: 264 file: 0, size: 0
2011/07/20 12:02:20 [debug] 99556#0: *15 http write filter: l:0 f:0 s:264
2011/07/20 12:02:20 [debug] 99556#0: *15 http cacheable: 0
2011/07/20 12:02:20 [debug] 99556#0: *15 http file cache free, fd: -1
2011/07/20 12:02:20 [debug] 99556#0: slab free: 0000000101030080
....................................................................................................................................


Подскажите, чего я не вижу.

Евгений
jt at aaanet.ru