slf4j-logback-Appender進階
阿新 • • 發佈:2019-02-06
logback 進階
基礎篇請參考http://wangxinchun.iteye.com/blog/2094761
1、AsyncAppender。
為了提升效能,logback 支援非同步的日誌記錄。
關於非同步:本質就是執行不等待,所謂執行就是提交任務。任務執行 1、有結果:一般認為執行完可以回撥;2、沒有結果,執行完就完事。log的記錄就是典型的沒有結果的情況,呼叫方不需要知道執行的最終結果。
實現: logback 是通過 ch.qos.logback.classic.AsyncAppender 來實現非同步的log日誌記錄的。其內部實現是通過儲存一個 BlockingQueue<E> blockingQueue; 來快取 (info,warn,error)的日誌訊息,然後由一個 Worker worker 執行緒 從 blockingQueue 中 blockingQueue.take();資料,並輸出到 AppenderAttachableImpl<E> aai 中。
關於BlockingQueue的使用 是非同步的重點,請參考:
http://wangxinchun.iteye.com/blog/1882960
相關原始碼:
Java程式碼
使用case:
Java程式碼
測試:
Java程式碼
結論:
同步的耗時:3800 左右
非同步的耗時:4500 左右
另外:考慮到實際的生產環境,大多不會把快取填滿,非同步的效能優勢會更明顯。
請注意:如果迴圈非常快,有可能佇列很快就滿了,當前執行緒要等待work執行緒把資料輸出到appender,這樣的測試是不能顯示非同步appender的優勢的,所以i%1000==0 進行了短暫的等待。
2、RollingFileAppender
迴圈檔案輸出appender,特點:可以根據rollingPolicy 來指定檔名的規則,按照時間或自增id ,保證log輸出到由(%d)當前時間指定的特定的檔案內,同一個時間規則,有可以根據(%i)來根據檔案的大小分多個檔案。
對於RollingFileAppender 一般必須要指定 rollingPolicy 和 triggeringPolicy,由於TimeBasedRollingPolicy 實現了TriggeringPolicy 和 RollingPolicy 所以如果rollingPolicy 配置為 TimeBasedRollingPolicy ,可以不配置triggeringPolicy。
TimeBasedRollingPolicy案例如下:
Java程式碼
輸出的效果如下:
FixedWindowRollingPolicy
固定格式,固定檔案大小的rooling策略。
案例如下:
Java程式碼
基礎篇請參考http://wangxinchun.iteye.com/blog/2094761
1、AsyncAppender。
為了提升效能,logback 支援非同步的日誌記錄。
關於非同步:本質就是執行不等待,所謂執行就是提交任務。任務執行 1、有結果:一般認為執行完可以回撥;2、沒有結果,執行完就完事。log的記錄就是典型的沒有結果的情況,呼叫方不需要知道執行的最終結果。
實現: logback 是通過 ch.qos.logback.classic.AsyncAppender 來實現非同步的log日誌記錄的。其內部實現是通過儲存一個 BlockingQueue<E> blockingQueue; 來快取 (info,warn,error)的日誌訊息,然後由一個 Worker worker 執行緒 從 blockingQueue 中 blockingQueue.take();資料,並輸出到 AppenderAttachableImpl<E> aai 中。
關於BlockingQueue的使用 是非同步的重點,請參考:
http://wangxinchun.iteye.com/blog/1882960
相關原始碼:
Java程式碼
- public class AsyncAppenderBase<E> extends UnsynchronizedAppenderBase<E> implements AppenderAttachable<E> {
- //內部要輸出的appender
-
AppenderAttachableImpl<E> aai = new AppenderAttachableImpl<E>();
- // log 訊息佇列
- BlockingQueue<E> blockingQueue;
- /**
- * 預設的訊息佇列,一般可以稍大一點*/
- public static final int DEFAULT_QUEUE_SIZE = 256;
- int queueSize = DEFAULT_QUEUE_SIZE;
- int appenderCount = 0;
- static final int UNDEFINED = -1;
- int discardingThreshold = UNDEFINED;
-
//實際的log 輸出執行緒任務
- Worker worker = new Worker();
- //其他略
- }
使用case:
Java程式碼
- <!-- 迴圈檔案輸出(基於時間戳的分檔案,是實際專案中用途最廣的一種情況) -->
- <appender name="ROLLING_FILE" class="ch.qos.logback.core.rolling.RollingFileAppender">
- <file>rooling.log</file>
- <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
- <fileNamePattern>rooling-log.%d{yyyy-MM-dd-HH}.log.gz</fileNamePattern>
- <maxHistory>30</maxHistory>
- </rollingPolicy>
- <encoder>
- <pattern>%-4relative [%thread] %-5level %logger{35} - %msg%n </pattern>
- </encoder>
- </appender>
- <appender name="SYNC_ROLLING_FILE" class="ch.qos.logback.classic.AsyncAppender">
- <appender-ref ref="ROLLING_FILE"/>
- <queueSize>1024</queueSize>
- <discardingThreshold>0</discardingThreshold>
- </appender>
- <logger name="com.qunar.logback.LogbackTest" additivity="false"
- level="debug">
- <!-- <appender-ref ref="SYNC_ROLLING_FILE" /> -->
- <appender-ref ref="ROLLING_FILE" />
- </logger>
測試:
Java程式碼
- @Test
- public void testLogback() throws InterruptedException {
- long l = System.currentTimeMillis();
- for(int i=0;i<30000;i++){
- logger.debug("hello {} ", "world" +i);
- logger.info("hello {} ", "world"+i);
- logger.warn("hello {} ", "world"+i);
- logger.error("hello {} ", "world"+i);
- if(i%1000 == 0){
- Thread.currentThread().sleep(50);
- }
- }
- System.out.println(System.currentTimeMillis() - l);
- Thread.currentThread().sleep(1000);
- }
結論:
同步的耗時:3800 左右
非同步的耗時:4500 左右
另外:考慮到實際的生產環境,大多不會把快取填滿,非同步的效能優勢會更明顯。
請注意:如果迴圈非常快,有可能佇列很快就滿了,當前執行緒要等待work執行緒把資料輸出到appender,這樣的測試是不能顯示非同步appender的優勢的,所以i%1000==0 進行了短暫的等待。
2、RollingFileAppender
迴圈檔案輸出appender,特點:可以根據rollingPolicy 來指定檔名的規則,按照時間或自增id ,保證log輸出到由(%d)當前時間指定的特定的檔案內,同一個時間規則,有可以根據(%i)來根據檔案的大小分多個檔案。
對於RollingFileAppender 一般必須要指定 rollingPolicy 和 triggeringPolicy,由於TimeBasedRollingPolicy 實現了TriggeringPolicy 和 RollingPolicy 所以如果rollingPolicy 配置為 TimeBasedRollingPolicy ,可以不配置triggeringPolicy。
TimeBasedRollingPolicy案例如下:
Java程式碼
- <?xml version="1.0" encoding="UTF-8"?>
- <configuration scan="true" scanPeriod="30 seconds">
- <contextName>myAppName</contextName>
- <!-- 命令列輸出 -->
- <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
- <encoder>
- <pattern>%d{HH:mm:ss.SSS} %contextName [%thread] %-5level %logger{36}- %msg%n</pattern>
- </encoder>
- </appender>
- <!-- 迴圈檔案輸出(基於時間戳的分檔案,是實際專案中用途最廣的一種情況) -->
- <appender name="ROLLING_FILE" class="ch.qos.logback.core.rolling.RollingFileAppender">
- <file>rooling.log</file>
- <rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
- <fileNamePattern>rooling-log.%d{yyyy-MM-dd-HH}.%i.log.gz</fileNamePattern>
- <maxHistory>30</maxHistory>
- <timeBasedFileNamingAndTriggeringPolicy class="ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP">
- <maxFileSize>1MB</maxFileSize>
- </timeBasedFileNamingAndTriggeringPolicy>
- </rollingPolicy>
- <encoder>
- <pattern>%d{HH:mm:ss.SSS} %contextName [%thread] %-5level %logger{36}- %msg%n</pattern>
- </encoder>
- </appender>
- <logger name="com.qunar.logback.LogbackTest" additivity="true"
- level="debug">
- <appender-ref ref="ROLLING_FILE" />
- </logger>
- <root level="INFO">
- <appender-ref ref="STDOUT" />
- </root>
- </configuration>
輸出的效果如下:
FixedWindowRollingPolicy
固定格式,固定檔案大小的rooling策略。
案例如下:
Java程式碼
- <?xml version="1.0" encoding="UTF-8"?>
- <configuration scan="true" scanPeriod="30 seconds">
- <contextName>myAppName</contextName>
- <!-- 命令列輸出 -->
- <appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
- <encoder>
- <pattern>%d{HH:mm:ss.SSS} %contextName [%thread] %-5level %logger{36}- %msg%n</pattern>
- </encoder>
- </appender>
- <!-- 迴圈檔案輸出(基於時間戳的分檔案,是實際專案中用途最廣的一種情況) -->
- <appender name="ROLLING_FILE" class="ch.qos.logback.core.rolling.RollingFileAppender">
- <file>test.log</file>
- <rollingPolicy class="ch.qos.logback.core.rolling.FixedWindowRollingPolicy">
- <fileNamePattern>tests.%i.log.zip</fileNamePattern>
- <minIndex>1</minIndex>
- <maxIndex>3</maxIndex>
- </rollingPolicy>
- <triggeringPolicy class="ch.qos.logback.core.rolling.SizeBasedTriggeringPolicy">
- <maxFileSize>1MB</maxFileSize>
- </triggeringPolicy>
- <encoder>
- <pattern>%-4relative [%thread] %-5level %logger{35} - %msg%n </pattern>
- </encoder>
- </appender>
- <logger name="com.qunar.logback.LogbackTest" additivity="false"
- level="debug">
- <appender-ref ref="ROLLING_FILE" />
- </logger>
- <root level="INFO">
- <appender-ref ref="STDOUT" />
- </root>
- </configuration>