目錄

• 一、默認的日志格式
• 二、我使用的日志格式
• 三、參數
• 四、測試效果
• 總結

上線了一個小的預約程序，配置通過Nginx進行訪問入口，默認的日志是沒有請求時間的，因此需要配置一下，將每一次的請求的訪問響應時間記錄出來，備查與優化使用.

一、默認的日志格式

默認的日志格式如下(默認是注解掉的，系統也自動會使用)：

    #log_format  main  "$remote_addr - $remote_user [$time_local] "$request" "    #  "$status $body_bytes_sent "$http_referer" "    #  ""$http_user_agent" "$http_x_forwarded_for"";    #access_log  logs/access.log  main;

二、我使用的日志格式

我僅僅在默認的基礎上加上響應時間的兩個我較關心的參數：request_time與upstream_response_time

將以下的配置開放并修改(我后面用了格式2，時間在前面，容易查看)：

帶時間數據參數的日志格式1

    log_format  main  "$remote_addr - $remote_user [$time_local] "$request" "      "$status $body_bytes_sent "$http_referer" "      ""$http_user_agent" "$http_x_forwarded_for" "$request_time" "$upstream_response_time"";    access_log  logs/access.log  main;

調整了下時間參數的顯示順序的格式2:

    log_format  main  "$remote_addr - $remote_user [$request_time $upstream_response_time] [$time_local] "$request" "      "$status $body_bytes_sent "$http_referer" "      ""$http_user_agent" "$http_x_forwarded_for"";    access_log  logs/access.log  main;

注意的是：log_format與access_log的注釋都要放開，僅放開log_format也是不生效的.

#設置成格式2后，可以用相應的正則表達式，查看大于1秒的日志，分兩步如下：

##1.高亮時間數據的正則表達式tail -f access.log |grep "\[[0-9]\.[0-9][0-9][0-9] [0-9]\.[0-9][0-9][0-9]\]"##2.大于1秒的日志的正則表達式,即將第一個數字改成[1-9]即可tail -f access.log |grep "\[[1-9]\.[0-9][0-9][0-9] [0-9]\.[0-9][0-9][0-9]\]"

三、參數

說明

• $remote_addr：客戶端地址
• $remote_user：客戶端用戶名稱
• $time_local：訪問時間和時區
• $request：請求的URI和HTTP協議
• $status：HTTP請求狀態
• $body_bytes_sent：發送給客戶端文件內容大小
• $http_referer：url跳轉來源
• $http_user_agent：用戶終端瀏覽器等信息
• $http_host：請求地址，即瀏覽器中你輸入的地址（IP或域名）
• $request_time：處理請求的總時間,包含了用戶數據接收時間
• $upstream_response_time：建立連接和從上游服務器接收響應主體的最后一個字節之間的時間
• $upstream_connect_time：花費在與上游服務器建立連接上的時間
• $upstream_header_time：建立連接和從上游服務器接收響應頭的第一個字節之間的時間

四、測試效果

修改前默認日志

127.0.0.1 - - [03/May/2022:12:02:51 +0800] "GET /byhsyyfront/byPages/ HTTP/1.1" 304 0 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/99.0.4844.51 Safari/537.36"
127.0.0.1 - - [03/May/2022:12:02:51 +0800] "GET /byhsyyGateway/byhsyySystem/verifyCode/getVerifyCode HTTP/1.1" 200 2553 "http://localhost:8881/byhsyyfront/byPages/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/99.0.4844.51 Safari/537.36"

修改后日志

127.0.0.1 - - [03/May/2022:12:00:47 +0800] "GET /byhsyyfront/byPages/ HTTP/1.1" 304 0 "-" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/99.0.4844.51 Safari/537.36" "-" "0.025" "0.025"
127.0.0.1 - - [03/May/2022:12:00:47 +0800] "GET /byhsyyGateway/byhsyySystem/verifyCode/getVerifyCode HTTP/1.1" 200 2178 "http://localhost:8881/byhsyyfront/byPages/" "Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/99.0.4844.51 Safari/537.36" "-" "0.037" "0.037"

可以看到修改后的最后多了兩個關于時間的參數數據，可以用于響應時間快慢分析.

總結

以上為個人經驗，希望能給大家一個參考，也希望大家多多支持。