Skip to content

Instantly share code, notes, and snippets.

@JohnBat26
Created July 18, 2012 14:12
Show Gist options
  • Save JohnBat26/3136421 to your computer and use it in GitHub Desktop.
Save JohnBat26/3136421 to your computer and use it in GitHub Desktop.
Лог для 280 мс
В Firefox 14 кэш отключен полностью и вызывается команда Ctrl-F5
FF:
Заголовки запроса:
Accept text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8
Accept-Encoding gzip, deflate
Accept-Language ru,ru-ru;q=0.8,en-us;q=0.5,en;q=0.3
Connection keep-alive
Cookie JSESSIONID=1kvava43dgbzc7rzb9x17mmqt
Host localhost
User-Agent Mozilla/5.0 (X11; Linux x86_64; rv:14.0) Gecko/20100101 Firefox/14.0.1
---
Заголовки ответа:
Cache-Control max-age=3600
Connection keep-alive
Content-Type image/png
Date Wed, 18 Jul 2012 14:09:28 GMT
Expires Wed, 18 Jul 2012 15:09:28 GMT
Keep-Alive timeout=45
Server nginx
Set-Cookie JSESSIONID=1kvava43dgbzc7rzb9x17mmqt;Path=/portal-facade-ng
Transfer-Encoding chunked
-------
кусок конфига:
location /portal-facade-ng/v1/adv/imageMap/{
proxy_cache banners-cache; # включаем proxy кэш в зону banners-cache
proxy_cache_key $scheme$proxy_host$uri; # ключ для кэша
proxy_cache_valid 200 302 1h; # кэшируем 200 и 302 ответы на 24 часа
proxy_cache_valid 404 10m; # 404 ответы кэшируем на 10 минут
proxy_ignore_headers X-Accel-Redirect; # некоторые заголовки игнорируем
proxy_ignore_headers X-Accel-Expires Expires Cache-Control;
proxy_ignore_headers Set-Cookie;
expires 1h; # проставляем заголовки для браузера
add_header Content-Type image/png;
#add_header Cache-status $upstream_cache_status;
default_type image/png;
proxy_pass http://127.0.0.1:8080;
error_log /var/log/nginx/adv.log debug;
error_page 404 = @404;
error_page 502 = @502;
error_page 504 = @504;
#index index.html index.htm;
}
-------------
DEBUG LOG
2012/07/18 18:09:28 [debug] 2972#0: *8841 http cl:-1 max:1048576
2012/07/18 18:09:28 [debug] 2972#0: *8841 rewrite phase: 3
2012/07/18 18:09:28 [debug] 2972#0: *8841 post rewrite phase: 4
2012/07/18 18:09:28 [debug] 2972#0: *8841 generic phase: 5
2012/07/18 18:09:28 [debug] 2972#0: *8841 generic phase: 6
2012/07/18 18:09:28 [debug] 2972#0: *8841 generic phase: 7
2012/07/18 18:09:28 [debug] 2972#0: *8841 access phase: 8
2012/07/18 18:09:28 [debug] 2972#0: *8841 access phase: 9
2012/07/18 18:09:28 [debug] 2972#0: *8841 post access phase: 10
2012/07/18 18:09:28 [debug] 2972#0: *8841 posix_memalign: 0000000001A76FC0:4096 @16
2012/07/18 18:09:28 [debug] 2972#0: *8841 http init upstream, client timer: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 epoll add event: fd:4 op:3 ev:80000005
2012/07/18 18:09:28 [debug] 2972#0: *8841 http script var: "http"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http script var: "127.0.0.1:8080"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http script var: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http cache key: "http127.0.0.1:8080/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp"
2012/07/18 18:09:28 [debug] 2972#0: *8841 add cleanup: 0000000001A75FC8
2012/07/18 18:09:28 [debug] 2972#0: *8841 http file cache exists: 0 e:1
2012/07/18 18:09:28 [debug] 2972#0: *8841 cache file: "/var/cache/nginx/banners/4/c4/d5a2677e79da11872e09e41e92b85c44"
2012/07/18 18:09:28 [debug] 2972#0: *8841 add cleanup: 0000000001A75FE0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http file cache fd: 59
2012/07/18 18:09:28 [debug] 2972#0: *8841 read: 59, 0000000001A774C0, 432, 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http upstream cache: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy status 200 "200 OK"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Expires: Thu, 01-Jan-1970 00:00:00 GMT"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Set-Cookie: JSESSIONID=1kvava43dgbzc7rzb9x17mmqt;Path=/portal-facade-ng"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Cache-Control: no-cache"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Content-Type: image/png"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Date: Wed, 18 Jul 2012 13:32:15 GMT"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Accept-Ranges: bytes"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Server: Restlet-Framework/2.0.14"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header: "Connection: close"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http proxy header done
2012/07/18 18:09:28 [debug] 2972#0: *8841 http file cache send: /var/cache/nginx/banners/4/c4/d5a2677e79da11872e09e41e92b85c44
2012/07/18 18:09:28 [debug] 2972#0: *8841 xslt filter header
2012/07/18 18:09:28 [debug] 2972#0: *8841 HTTP/1.1 200 OK
Server: nginx
Date: Wed, 18 Jul 2012 14:09:28 GMT
Content-Type: image/png
Transfer-Encoding: chunked
Connection: keep-alive
Keep-Alive: timeout=45
Expires: Wed, 18 Jul 2012 15:09:28 GMT
Set-Cookie: JSESSIONID=1kvava43dgbzc7rzb9x17mmqt;Path=/portal-facade-ng
Cache-Control: max-age=3600
Content-Type: image/png
2012/07/18 18:09:28 [debug] 2972#0: *8841 write new buf t:1 f:0 0000000001A77B28, pos 0000000001A77B28, size: 339 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter: l:0 f:0 s:339
2012/07/18 18:09:28 [debug] 2972#0: *8841 http output filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 image filter
2012/07/18 18:09:28 [debug] 2972#0: *8841 xslt filter body
2012/07/18 18:09:28 [debug] 2972#0: *8841 http postpone filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" 00007FFF9F780FB0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http chunk: 412774
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:1 f:0 0000000001A77B28, pos 0000000001A77B28, size: 339 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 write new buf t:1 f:0 0000000001A77D78, pos 0000000001A77D78, size: 7 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 write new buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 432, size: 412774
2012/07/18 18:09:28 [debug] 2972#0: *8841 write new buf t:0 f:0 0000000000000000, pos 00000000004989D8, size: 7 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter: l:1 f:0 s:413127
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter limit 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 writev: 346
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: @432 134736
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: 134736, @432 134736:134736
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter 0000000001A77E00
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer add: 4: 1:1342620568550
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: -2 "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http finalize request: -2, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" a:1, c:2
2012/07/18 18:09:28 [debug] 2972#0: *8841 http finalize request: -4, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" a:1, c:2
2012/07/18 18:09:28 [debug] 2972#0: *8841 http request count:2 blk:0
2012/07/18 18:09:28 [debug] 2972#0: *8841 post event 00007F1A9940C148
2012/07/18 18:09:28 [debug] 2972#0: *8841 delete posted event 00007F1A9940C148
2012/07/18 18:09:28 [debug] 2972#0: *8841 http run request: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer handler: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer delayed
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer del: 4: 1342620568550
2012/07/18 18:09:28 [debug] 2972#0: *8841 http run request: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer handler: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http output filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 image filter
2012/07/18 18:09:28 [debug] 2972#0: *8841 xslt filter body
2012/07/18 18:09:28 [debug] 2972#0: *8841 http postpone filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" 0000000000000000
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 135168, size: 278038
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:0 0000000000000000, pos 00000000004989D8, size: 7 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter: l:1 f:0 s:278045
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter limit 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: @135168 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: 131072, @135168 131072:131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter 0000000001A77E00
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer add: 4: 1:1342620568649
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: -2 "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer output filter: -2, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer del: 4: 1342620568649
2012/07/18 18:09:28 [debug] 2972#0: *8841 http run request: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer handler: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http output filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 image filter
2012/07/18 18:09:28 [debug] 2972#0: *8841 xslt filter body
2012/07/18 18:09:28 [debug] 2972#0: *8841 http postpone filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" 0000000000000000
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 266240, size: 146966
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:0 0000000000000000, pos 00000000004989D8, size: 7 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter: l:1 f:0 s:146973
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter limit 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: @266240 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: 131072, @266240 131072:131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter 0000000001A77E00
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer add: 4: 1:1342620568750
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: -2 "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer output filter: -2, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer del: 4: 1342620568750
2012/07/18 18:09:28 [debug] 2972#0: *8841 http run request: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer handler: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http output filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 image filter
2012/07/18 18:09:28 [debug] 2972#0: *8841 xslt filter body
2012/07/18 18:09:28 [debug] 2972#0: *8841 http postpone filter "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" 0000000000000000
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:1 0000000000000000, pos 0000000000000000, size: 0 file: 397312, size: 15894
2012/07/18 18:09:28 [debug] 2972#0: *8841 write old buf t:0 f:0 0000000000000000, pos 00000000004989D8, size: 7 file: 0, size: 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter: l:1 f:0 s:15901
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter limit 131072
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: @397312 15894
2012/07/18 18:09:28 [debug] 2972#0: *8841 sendfile: 15894, @397312 15894:15894
2012/07/18 18:09:28 [debug] 2972#0: *8841 writev: 7
2012/07/18 18:09:28 [debug] 2972#0: *8841 http write filter 0000000000000000
2012/07/18 18:09:28 [debug] 2972#0: *8841 http copy filter: 0 "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer output filter: 0, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http writer done: "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000"
2012/07/18 18:09:28 [debug] 2972#0: *8841 http finalize request: 0, "/portal-facade-ng/v1/adv/imageMap/DEFAULT/TOP/ru_RU,270,90,v,png,2,transp?expiredTime=1342602000000" a:1, c:1
2012/07/18 18:09:28 [debug] 2972#0: *8841 set http keepalive handler
2012/07/18 18:09:28 [debug] 2972#0: *8841 http close request
2012/07/18 18:09:28 [debug] 2972#0: *8841 http log handler
2012/07/18 18:09:28 [debug] 2972#0: *8841 run cleanup: 0000000001A75FE0
2012/07/18 18:09:28 [debug] 2972#0: *8841 file cleanup: fd:59
2012/07/18 18:09:28 [debug] 2972#0: *8841 run cleanup: 0000000001A75FC8
2012/07/18 18:09:28 [debug] 2972#0: *8841 http file cache cleanup
2012/07/18 18:09:28 [debug] 2972#0: *8841 http file cache free, fd: 59
2012/07/18 18:09:28 [debug] 2972#0: *8841 free: 0000000001A75000, unused: 2
2012/07/18 18:09:28 [debug] 2972#0: *8841 free: 0000000001A76FC0, unused: 135
2012/07/18 18:09:28 [debug] 2972#0: *8841 event timer add: 4: 45000:1342620613849
2012/07/18 18:09:28 [debug] 2972#0: *8841 free: 0000000001B4A840
2012/07/18 18:09:28 [debug] 2972#0: *8841 free: 0000000001A4C760
2012/07/18 18:09:28 [debug] 2972#0: *8841 hc free: 0000000000000000 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 hc busy: 0000000000000000 0
2012/07/18 18:09:28 [debug] 2972#0: *8841 tcp_nodelay
2012/07/18 18:09:28 [debug] 2972#0: *8841 reusable connection: 1
2012/07/18 18:09:28 [debug] 2972#0: *8841 post event 00007F1AAD2DE148
2012/07/18 18:09:28 [debug] 2972#0: *8841 delete posted event 00007F1AAD2DE148
2012/07/18 18:09:28 [debug] 2972#0: *8841 http keepalive handler
2012/07/18 18:09:28 [debug] 2972#0: *8841 malloc: 0000000001A4C760:1024
2012/07/18 18:09:28 [debug] 2972#0: *8841 recv: fd:4 -1 of 1024
2012/07/18 18:09:28 [debug] 2972#0: *8841 recv() not ready (11: Resource temporarily unavailable)
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment