Nginx 学习之 request_time 和 upstream_response_time

xhqiang

已于 2022-12-21 15:33:40 修改

阅读量835

点赞数

分类专栏： Nginx 文章标签： nginx 学习日志 request_time

于 2022-12-21 15:32:10 首次发布

本文链接：https://blog.csdn.net/xhqiang/article/details/128397749

版权

Nginx 专栏收录该内容

1 篇文章 0 订阅

订阅专栏

Nginx 学习之 request_time 和 upstream_response_time

为了记录接口响应时间，会通过调整Nginx日志格式来记录接口的请求和响应详细情况

项目采用的日志结构如下：

log_format  main  '$remote_addr - $remote_user [$time_local] "$request" $request_time $upstream_response_time '
                      '$status $body_bytes_sent "$http_referer" '
                      '"$http_user_agent" "$http_x_forwarded_for"';

最近在分析接口时发现某个接口的请求详情如下：

192.168.1.11 - - [02/Dec/2022:11:01:35 +0800] "POST /xxx/xxxx HTTP/1.1" 5.687 0.146 200 535863 "http://localhost:7456/" "Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/107.0.0.0 Safari/537.36" "-"

分析接口发现接口的两个时间差别很大这里就需要分析下两个接口的时间了，然后来定位问题的原因

request_time ：指的就是从接受用户请求的第一个字节到发送完响应数据的时间，即$request_time包括接收客户端请求数据的时间、后端程序响应的时间、发送响应数据给客户端的时间(不包含写日志的时间)

upstream_response_time：是指从Nginx向后端建立连接开始到接受完数据然后关闭连接为止的时间

上面描述可以看出， request_time肯定比upstream_response_time值大，尤其是在客户端采用POST方式提交较大的数据，响应体比较大的时候。在客户端网络条件差的时候，request还会被放大

出现这个问题的原因大概是因为网络问题导致的，看到这个接口返回的数据量比较大，在网络不好的情况下这个差距估计被放大了，之前这两个时间基本都是相等的或者差别在ms级别