nginx日志打印响应时间request_time和upstream_response_time

深碍√TFBOYSˉ_ 2022-04-06 09:29 2255阅读 0赞

设置log\_format，添加request\_time，$upstream\_response\_time，位置随意

og\_format  main  **'"$request\_time" "$upstream\_response\_time"** $remote\_addr - $remote\_user \[$time\_local\] "$request" '

'$status $body\_bytes\_sent "$http\_referer" '

'"$http\_user\_agent" "$http\_x\_forwarded\_for"';

日志输出效果：

**"0.015" "0.015"** 10.1.2.3 - - \[20/Mar/2017:04:05:49 +0800\] "GET /myApp/servlet/TestServlet HTTP/1.1" 200 52 "-" "Mozilla/4.0 (compatible; MSIE 4.0; Windows NT)" "-"

\-------------------------------------------------------------------------------

笔者在根据nginx的accesslog中$request\_time进行程序优化时，发现有个接口，直接返回数据，平均的$request\_time也比较大。原来$request\_time包含了用户数据接收时间，而真正程序的响应时间应该用$upstream\_response\_time。

下面介绍下2者的差别：

1、request\_time

官网描述：request processing time in seconds with a milliseconds resolution; time elapsed between the first bytes were read from the client and the log write after the last bytes were sent to the client 。

指的就是从接受用户请求的第一个字节到发送完响应数据的时间，即包括接收请求数据时间、程序响应时间、输出

响应数据时间。

2、upstream\_response\_time

官网描述：keeps times of responses obtained from upstream servers; times are kept in seconds with a milliseconds resolution. Several response times are separated by commas and colons like addresses in the $upstream\_addr variable

是指从Nginx向后端（php-cgi)建立连接开始到接受完数据然后关闭连接为止的时间。

从上面的描述可以看出，$request\_time肯定比$upstream\_response\_time值大，特别是使用POST方式传递参数时，因为Nginx会把request body缓存住，接受完毕后才会把数据一起发给后端。所以如果用户网络较差，或者传递数据较大时，$request\_time会比$upstream\_response\_time大很多。

所以如果使用nginx的accesslog查看php程序中哪些接口比较慢的话，记得在log\_format中加入$upstream\_response\_time。

参考：

nginx优化之request\_time 和upstream\_response\_time差别

http://wuzhangshu927.blog.163.com/blog/static/114224687201310674652147/