子请求location的proxy_cache 的proxy_cache_key 无效，

yongmingliu

春哥，您好！

我在openresty 用ngx.location.capture发起自请求，发现子请求location的proxy_cache 的proxy_cache_key 无效，

sub location 没有冲突的lua 上下文，理论上应该可以用proxy_cache的呀。

and the openresy config :

proxy_cache_path /tmp/cache levels=1 keys_zone=static:10m;

server {

listen 80;

server_name localhost;

access_log logs/demo.access.log main;

error_log logs/demo.error.log debug;

root /tmp/hmtl/;

location ~* ^/diymusic/.*\.(mp3|jpg)$ {

proxy_cache static; # this line is crucial or the cache will be disabled!

access_by_lua '

--rewrite request_uri--

local uri = ngx.var.request_uri

local rw_uri, n, err = ngx.re.gsub(uri, "^(/diymusic[/DiyFile/]*)(/?.*)", "/nets/diy/$2")

local res, err = ngx.location.capture(rw_uri,

{ method = ngx.HTTP_GET,

args = {flag = "node"}

});

if ( res.status == 200 or res.status == 206 ) then

if res.header then

for k ,v in pairs(res.header) do

ngx.header[k] = v

end

ngx.status = res.status

ngx.say(res.body)

else

ngx.exit(404)

end

else

ngx.exec("@node2")

end

}

location ~ /nets/(.*) {

proxy_cache static; #

proxy_pass http://node1;

proxy_cache_key $host$uri;

proxy_ignore_headers "Cache-Control" "Expires" "Set-Cookie";

more_set_headers "X-Cache-Status: $upstream_cache_status";

internal;

}

location @node2 {

proxy_cache static; # this line is crucial or the cache will be disabled! ??

proxy_pass http://node2;

proxy_cache_key $host$uri;

proxy_ignore_headers "Cache-Control" "Expires" "Set-Cookie";

more_set_headers "X-Cache-Status: $upstream_cache_status";

}

location ~ (mp3|jpg)$ {

expires 10d;

}

————————————————————————————————————————————————————

my openresty configure:

nginx version: openresty/1.9.15.1

built by gcc 4.8.5 20150623 (Red Hat 4.8.5-4) (GCC)

built with OpenSSL 1.0.1e-fips 11 Feb 2013

TLS SNI support enabled

configure arguments: --prefix=/usr/local/openresty/nginx --with-cc-opt=-O2 --add-module=../ngx_devel_kit-0.3.0 --add-module=../echo-nginx-module-0.59 --add-module=../xss-nginx-module-0.05 --add-module=../ngx_coolkit-0.2rc3 --add-module=../set-misc-nginx-module-0.30 --add-module=../form-input-nginx-module-0.12 --add-module=../encrypted-session-nginx-module-0.05 --add-module=../srcache-nginx-module-0.31 --add-module=../ngx_lua-0.10.5 --add-module=../ngx_lua_upstream-0.05 --add-module=../headers-more-nginx-module-0.30 --add-module=../array-var-nginx-module-0.05 --add-module=../memc-nginx-module-0.17 --add-module=../redis2-nginx-module-0.13 --add-module=../redis-nginx-module-0.3.7 --add-module=../rds-json-nginx-module-0.14 --add-module=../rds-csv-nginx-module-0.07 --with-ld-opt=-Wl,-rpath,/usr/local/openresty/luajit/lib --with-stream --with-stream_ssl_module --add-module=/root/stream-lua-nginx-module-0.0.1 --with-http_ssl_module

—————————————————————————————————————————————————————————

I sure module headers-more-nginx-module is after lua-nginx-module ,but cache is MISS

Connection →keep-alive

Content-Length →879394

Content-Type →image/jpeg

Date →Thu, 27 Oct 2016 09:37:50 GMT

ETag →"5811b61b-d6b22"

Last-Modified →Thu, 27 Oct 2016 08:08:59 GMT

Server →openresty/1.9.15.1

X-Cache-Status →MISS

yongmingliu

更新：增加了proxy_cache_valid 200 304 60m; 指令后，debug看到确实命中缓存了，response headers也看到X-Cache-Status →HIT，但诡异的是日志$upstream_cache_status字段没输出HIT。这是为什么？

2016/10/28 14:25:17 [debug] 27584#0: *41 write new buf t:1 f:0 00007F8DA177F460, pos 00007F8DA177F460, size: 249 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter: l:0 f:0 s:249

2016/10/28 14:25:17 [debug] 27584#0: *41 http output filter "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 lua capture body filter, uri "/diymusic/test.jpg"

2016/10/28 14:25:17 [debug] 27584#0: *41 http postpone filter "/diymusic/test.jpg?" 00007F8DA177F2B0

2016/10/28 14:25:17 [debug] 27584#0: *41 write old buf t:1 f:0 00007F8DA177F460, pos 00007F8DA177F460, size: 249 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 write new buf t:1 f:0 00007F8D9CF64010, pos 00007F8D9CF64010, size: 879395 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter: l:0 f:0 s:879644

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter limit 0

2016/10/28 14:25:17 [debug] 27584#0: *41 writev: 68000 of 879644

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter 00007F8DA177F5D8

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: -2 "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 lua resume returned 0

2016/10/28 14:25:17 [debug] 27584#0: *41 lua light thread ended normally

2016/10/28 14:25:17 [debug] 27584#0: *41 lua deleting light thread

2016/10/28 14:25:17 [debug] 27584#0: *41 lua run thread returned 0

2016/10/28 14:25:17 [debug] 27584#0: *41 lua sending last buf of the response body

2016/10/28 14:25:17 [debug] 27584#0: *41 http output filter "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 lua capture body filter, uri "/diymusic/test.jpg"

2016/10/28 14:25:17 [debug] 27584#0: *41 http postpone filter "/diymusic/test.jpg?" 00007FFECC1AFE10

2016/10/28 14:25:17 [debug] 27584#0: *41 write old buf t:1 f:0 00007F8D9CF64010, pos 00007F8D9CF748B7, size: 811644 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter: l:1 f:0 s:811644

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter limit 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter 00007F8DA177F5D8

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: -2 "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http finalize request: 200, "/diymusic/test.jpg?" a:1, c:1

2016/10/28 14:25:17 [debug] 27584#0: *41 event timer add: 16: 60000:1477635977373

2016/10/28 14:25:17 [debug] 27584#0: *41 http posted request: "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http writer handler: "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http output filter "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 lua capture body filter, uri "/diymusic/test.jpg"

2016/10/28 14:25:17 [debug] 27584#0: *41 http postpone filter "/diymusic/test.jpg?" 0000000000000000

2016/10/28 14:25:17 [debug] 27584#0: *41 write old buf t:1 f:0 00007F8D9CF64010, pos 00007F8D9CF748B7, size: 811644 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 write old buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter: l:1 f:0 s:811644

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter limit 0

2016/10/28 14:25:17 [debug] 27584#0: *41 http write filter 00007F8DA177F5D8

2016/10/28 14:25:17 [debug] 27584#0: *41 http copy filter: -2 "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 http writer output filter: -2, "/diymusic/test.jpg?"

2016/10/28 14:25:17 [debug] 27584#0: *41 event timer: 16, old: 1477635977373, new: 1477635977373

2016/10/28 14:25:17 [debug] 27584#0: *41 post event 00007F8D9D36D190

2016/10/28 14:25:17 [debug] 27584#0: *41 delete posted event 00007F8D9D36D190

2016/10/28 14:25:17 [debug] 27584#0: *41 http run request: "/diymusic/test.jpg?"