SpringCloud升級之路2020.0.x版-14.UnderTow AccessLog 配置介紹

本系列程式碼地址：https://github.com/HashZhang/spring-cloud-scaffold/tree/master/spring-cloud-iiford

server:
  undertow:
    # access log相關配置
    accesslog:
      # 存放目錄，預設為 logs
      dir: ./log
      # 是否開啟
      enabled: true
      # 格式，各種佔位符後面會詳細說明
      pattern: '{
                  "transportProtocol":"%{TRANSPORT_PROTOCOL}",
                  "scheme":"%{SCHEME}",
                  "protocol":"%{PROTOCOL}",
                  "method":"%{METHOD}",
                  "reqHeaderUserAgent":"%{i,User-Agent}",
                  "cookieUserId": "%{c,userId}",
                  "queryTest": "%{q,test}",
                  "queryString": "%q",
                  "relativePath": "%R, %{REQUEST_PATH}, %{RESOLVED_PATH}",
                  "requestLine": "%r",
                  "uri": "%U",
                  "thread": "%I",
                  "hostPort": "%{HOST_AND_PORT}",
                  "localIp": "%A",
                  "localPort": "%p",
                  "localServerName": "%v",
                  "remoteIp": "%a",
                  "remoteHost": "%h",
                  "bytesSent": "%b",
                  "time":"%{time,yyyy-MM-dd HH:mm:ss.S}",
                  "status":"%s",
                  "reason":"%{RESPONSE_REASON_PHRASE}",
                  "respHeaderUserSession":"%{o,userSession}",
                  "respCookieUserId":"%{resp-cookie,userId}",
                  "timeUsed":"%Dms, %Ts, %{RESPONSE_TIME}ms, %{RESPONSE_TIME_MICROS} us, %{RESPONSE_TIME_NANOS} ns",
                }'
      # 檔案字首，預設為 access_log
      prefix: access.
      # 檔案字尾，預設為 log
      suffix: log
      # 是否另起日誌檔案寫 access log，預設為 true
      # 目前只能按照日期進行 rotate，一天一個日誌檔案
      rotate: true

Undertow 的 accesslog 處理核心類抽象是 io.undertow.server.handlers.accesslog.AccesslogReceiver。由於目前 Undertow 的 AccesslogReceiver 只有一種實現在使用，也就是 io.undertow.server.handlers.accesslog.DefaultAccessLogReceiver。

檢視 DefaultAccessLogReceiver 的 rotate 時機：

DefaultAccessLogReceiver

/**
 * 計算 rotate 時間點
 */
private void calculateChangeOverPoint() {
    Calendar calendar = Calendar.getInstance();
    calendar.set(Calendar.SECOND, 0);
    calendar.set(Calendar.MINUTE, 0);
    calendar.set(Calendar.HOUR_OF_DAY, 0);
    //當前時間日期 + 1，即下一天
    calendar.add(Calendar.DATE, 1);
    SimpleDateFormat df = new SimpleDateFormat("yyyy-MM-dd", Locale.US);
    currentDateString = df.format(new Date());
    // if there is an existing default log file, use the date last modified instead of the current date
    if (Files.exists(defaultLogFile)) {
        try {
            currentDateString = df.format(new Date(Files.getLastModifiedTime(defaultLogFile).toMillis()));
        } catch(IOException e){
            // ignore. use the current date if exception happens.
        }
    }
    //rotate 時機是下一天的 0 點
    changeOverPoint = calendar.getTimeInMillis();
}

其實 Undertow 中的 accesslog 佔位符，就是之前我們提到的 Undertow Listener 解析請求後抽象的 HTTP server exchange 的屬性。

官網文件的表格並不是最全的，並且注意點並沒有說明，例如某些佔位符必須開啟某些 Undertow 特性才能使用等等。這裡我們列出下。

首先先提出一個注意點，引數佔位符，例如 %{i,你要看的header值} 檢視 header 的某個 key 的值。逗號後面注意不要有空格，因為這個空格會算入 key 裡面導致拿不到你想要的 key。

請求相關屬性

注意：

1. 路徑引數 PathVariable 由於並沒有被 Undertow 的 Listener 或者 Handler 解析處理，所以攔截不到，無法確認是否是一個 PathVariable 還是就是 url 路徑。所以，PathVariable 的佔位符是不會起作用的。

請求地址相關

注意：

1. 請求的遠端地址我們一般不從請求連接獲取，而是通過 Http Header 裡面的 X-forwarded-for 或者 X-real-ip 等獲取，因為現在請求都是通過各種 VPN，負載均衡器發上來的。

響應相關屬性

注意：預設 undertow 沒有開啟請求時間內統計，需要開啟才能統計響應時間，如何開啟呢？通過註冊一個 WebServerFactoryCustomizer 到 Spring ApplicationContext 中即可。請看下面的程式碼（專案地址：https://github.com/HashZhang/spring-cloud-scaffold/blob/master/spring-cloud-iiford/）：

spring.factories（省略無關程式碼）

# AutoConfiguration
org.springframework.boot.autoconfigure.EnableAutoConfiguration=\
    com.github.hashjang.spring.cloud.iiford.service.common.auto.UndertowAutoConfiguration

UndertowAutoConfiguration

//設定proxyBeanMethods=false，因為沒有 @Bean 的方法互相呼叫需要每次返回同一個 Bean，沒必要代理，關閉增加啟動速度
@Configuration(proxyBeanMethods = false)
@Import(WebServerConfiguration.class)
public class UndertowAutoConfiguration {
}

WebServerConfiguration

//設定proxyBeanMethods=false，因為沒有 @Bean 的方法互相呼叫需要每次返回同一個 Bean，沒必要代理，關閉增加啟動速度
@Configuration(proxyBeanMethods = false)
public class WebServerConfiguration {
    @Bean
    public WebServerFactoryCustomizer<ConfigurableUndertowWebServerFactory> undertowWebServerAccessLogTimingEnabler(ServerProperties serverProperties) {
        return new DefaultWebServerFactoryCustomizer(serverProperties);
    }
}

DefaultWebServerFactoryCustomizer

public class DefaultWebServerFactoryCustomizer implements WebServerFactoryCustomizer<ConfigurableUndertowWebServerFactory> {
    private final ServerProperties serverProperties;
    public DefaultWebServerFactoryCustomizer(ServerProperties serverProperties) {
        this.serverProperties = serverProperties;
    }
    @Override
    public void customize(ConfigurableUndertowWebServerFactory factory) {
        String pattern = serverProperties.getUndertow().getAccesslog().getPattern();
        // 如果 accesslog 配置中列印了響應時間，則開啟記錄請求開始時間配置
        if (logRequestProcessingTiming(pattern)) {
            factory.addBuilderCustomizers(builder -> builder.setServerOption(UndertowOptions.RECORD_REQUEST_START_TIME, true));
        }
    }
    private boolean logRequestProcessingTiming(String pattern) {
        if (StringUtils.isBlank(pattern)) {
            return false;
        }
        //判斷 accesslog 是否配置了檢視響應時間
        return pattern.contains(ResponseTimeAttribute.RESPONSE_TIME_MICROS)
                || pattern.contains(ResponseTimeAttribute.RESPONSE_TIME_MILLIS)
                || pattern.contains(ResponseTimeAttribute.RESPONSE_TIME_NANOS)
                || pattern.contains(ResponseTimeAttribute.RESPONSE_TIME_MILLIS_SHORT)
                || pattern.contains(ResponseTimeAttribute.RESPONSE_TIME_SECONDS_SHORT);
    }
}

其他

還有安全相關的屬性（SSL 相關，登入認證 Authentication 相關），微服務內部呼叫一般用不到，我們這裡就不贅述了。

其它內建的屬性，在 Spring Boot 環境下一般用不到，我們這裡就不討論了。

舉例

我們最開始配置的 accesslog 的例子請求返回如下（ JSON 格式化之後的結果）：

{
	"transportProtocol": "http/1.1",
	"scheme": "http",
	"protocol": "HTTP/1.1",
	"method": "GET",
	"reqHeaderUserAgent": "PostmanRuntime/7.26.10",
	"cookieUserId": "testRequestCookieUserId",
	"queryTest": "1",
	"queryString": "?test=1&query=2",
	"relativePath": "/test, /test, -",
	"requestLine": "GET /test?test=1&query=2 HTTP/1.1",
	"uri": "/test",
	"thread": "XNIO-2 task-1",
	"hostPort": "127.0.0.1:8102",
	"localIp": "127.0.0.1",
	"localPort": "8102",
	"localServerName": "127.0.0.1",
	"remoteIp": "127.0.0.1",
	"remoteHost": "127.0.0.1",
	"bytesSent": "26",
	"time": "2021-04-08 00:07:50.410",
	"status": "200",
	"reason": "OK",
	"respHeaderUserSession": "testResponseHeaderUserSession",
	"respCookieUserId": "testResponseCookieUserId",
	"timeUsed": "3683ms, 3.683s, 3683ms, 3683149 us, 3683149200 ns",
}

我們這一節詳細介紹瞭如何配置 Undertow 的 accesslog，將 accesslog 各種佔位符都羅列了出來，使用者可以根據這些資訊配置出自己想要的 accesslog 資訊以及格式。下一節，我們將詳細介紹我們框架中針對 Undertow 的定製程式碼

微信搜尋“我的程式設計喵”關注公眾號，每日一刷，輕鬆提升技術，斬獲各種offer：