2. 程式人生 > >Logback及Log4J效能測試

Logback及Log4J效能測試

阿新 • • 發佈：2019-02-05

分別從兩個方面測試：

1、日誌輸出的格式對效能的影響，即日誌中包含動態方法名和行號對效能的影響

2、日誌長度對效能的影響

LogBack測試

本測試在Spring Boot框架基礎上進行，日誌配置檔案logback-spring.xml內容如下：

<?xml version="1.0" encoding="UTF-8"?>
<configuration scan="true" scanPeriod="30 seconds">
	<property name="log.path" value="D:\\log\\eurekaServer\\" />
	<!-- 不帶方法名和行號 -->
	<appender name="LOGFILE"
		class="ch.qos.logback.core.rolling.RollingFileAppender">
		<File>${log.path}/eurekaServer.log</File>
		<rollingPolicy class="ch.qos.logback.core.rolling.TimeBasedRollingPolicy">
			<fileNamePattern>${log.path}/eurekaServer-%d{yyyyMMdd}.%i.log
			</fileNamePattern>
			<timeBasedFileNamingAndTriggeringPolicy
				class="ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP">
				<maxFileSize>50MB</maxFileSize>
			</timeBasedFileNamingAndTriggeringPolicy>
			<maxHistory>60</maxHistory>
			<totalSizeCap>20GB</totalSizeCap>
		</rollingPolicy>
		<layout class="ch.qos.logback.classic.PatternLayout">
			<pattern>%d{yyyy-MM-dd HH:mm:ss.SSS} %-5level [%-14.14thread] %-30.30logger{30} - %msg%n </pattern>
		</layout>
	</appender>
	<root level="info">
		<appender-ref ref="LOGFILE" />
	</root>
	<logger name="com.EurekaServer" level="INFO" additivity="false">
		<appender-ref ref="LOGFILE" />
	</logger>
</configuration>

只帶行號：

%d{yyyy-MM-dd HH:mm:ss.SSS} %-5level [%-14.14thread] %-30.30logger{30} %-4line - %msg%n

只帶方法名：

%d{yyyy-MM-dd HH:mm:ss.SSS} %-5level [%-14.14thread] %-30.30logger{30} [%-20.20method] - %msg%n 

帶方法名和行號：

%d{yyyy-MM-dd HH:mm:ss.SSS} %-5level [%-14.14thread] %-30.30logger{30} [%-20.20method]:%-4line - %msg%n 

因為基於Spring Boot，所以只需在測試類外面加上如下兩個註解即可：

@RunWith(SpringRunner.class)
@SpringBootTest

整個測試類內容如下：

import java.util.ArrayList;
import java.util.List;
import org.junit.Ignore;
import org.junit.Test;
import org.junit.runner.RunWith;
import org.perf4j.StopWatch;
import org.perf4j.slf4j.Slf4JStopWatch;
import org.slf4j.Logger;
import org.slf4j.LoggerFactory;
import org.springframework.boot.test.context.SpringBootTest;
import org.springframework.test.context.junit4.SpringRunner;

@RunWith(SpringRunner.class)
@SpringBootTest
public class EurekaServerApplicationTests {
    Logger log = LoggerFactory.getLogger(EurekaServerApplicationTests.class);  

	@Ignore
	@Test
	public void contextLoads() {
	}

//	@Ignore
	@Test  
    public void logMehodAndLine() throws InterruptedException{  
        StopWatch watch = new Slf4JStopWatch();  
        List<Long> times = new ArrayList<Long>();  
        int n = 10;  
        int size = 1000000;  
        Long totalTime = 0L;  
        for(int i = 0;i<n;i++){  
            watch.start();  
            for(int j = 0;j<size - 1;j++){  
                log.info("Example message");  
            }  
            watch.stop();  
            times.add(i, watch.getElapsedTime());  
            totalTime += watch.getElapsedTime();  
            Thread.sleep(500);  
        }  
        log.info("{}條耗時：{}ms",size,times.toString());  
        log.info("平均每{}條耗時：{}ms",size,totalTime/n);  
    }  
      
    /** 
     * 測試日誌長度對時間的影響 
     */  
	@Ignore  
    @Test  
    public void logLength() throws InterruptedException{  
        StopWatch watch = new Slf4JStopWatch();  
        List<Long> times = new ArrayList<Long>();  
        int n = 50;  
        int size = 10000;  
        int length = 1000;  
        StringBuffer info = new StringBuffer();  
        log.info("開始。。。。");
        for(int i = 0;i < n; i++){  
            while(info.length()<(i+1)*length){  
                info.append("a");  
            }  
            String infoStr = info.toString();  
            watch.start();  
            for(int j = 0;j<size - 1;j++){  
                log.info(infoStr);  
            }  
            watch.stop();  
            times.add(i, watch.getElapsedTime());  
            Thread.sleep(500);  
        }  
        for(int i = 0;i < n; i++){  
            log.info("長度為{}時，{}條共耗時：{}ms",(i+1)*length,size,times.get(i));  
        }  
    }  
}


日誌輸出的格式對效能的影響

包含行號測試結果：

2017-12-17 16:47:28.795 INFO  [main          ] E.EurekaServerApplicationTests 42   - 1000000條耗時：[55742, 55179, 54539, 55101, 56176, 56494, 56571, 55494, 55828, 56417]ms
2017-12-17 16:47:28.795 INFO  [main          ] E.EurekaServerApplicationTests 43   - 平均每1000000條耗時：55754ms

包含動態方法名測試結果：

2017-12-17 17:19:46.811 INFO  [main          ] E.EurekaServerApplicationTests [logMehodAndLine     ] - 1000000條耗時：[56293, 55258, 54957, 55100, 55357, 53849, 55144, 55342, 54842, 56428]ms
2017-12-17 17:19:46.811 INFO  [main          ] E.EurekaServerApplicationTests [logMehodAndLine     ] - 平均每1000000條耗時：55257ms

同時包含動態方法名和行號的測試結果：

2017-12-17 17:35:27.582 INFO  [main          ] E.EurekaServerApplicationTests [logMehodAndLine     ]:42   - 1000000條耗時：[59606, 57803, 57607, 57038, 58981, 57819, 58784, 58220, 60550, 57470]ms
2017-12-17 17:35:27.583 INFO  [main          ] E.EurekaServerApplicationTests [logMehodAndLine     ]:43   - 平均每1000000條耗時：58387ms

不包含動態方法名和行號的測試結果：

2017-12-17 17:49:30.144 INFO  [main          ] E.EurekaServerApplicationTests - 1000000條耗時：[3877, 3425, 3189, 3002, 4061, 3372, 3767, 3138, 4020, 2914]ms
2017-12-17 17:49:30.144 INFO  [main          ] E.EurekaServerApplicationTests - 平均每1000000條耗時：3476ms

可見，同時包含方法名和行號的時間與只包含方法名或行號差不多，但都比不包含方法名和行號需要的時間多，大概為16倍。

日誌長度對效能的影響

以包含方法名和行號、輸出到檔案為例

測試結果：

n=50、size = 10000、length = 1000時：

2017-12-17 15:35:55.726 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為1000時，10000條共耗時：884ms
2017-12-17 15:35:55.726 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為2000時，10000條共耗時：769ms
2017-12-17 15:35:55.726 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為3000時，10000條共耗時：1009ms
2017-12-17 15:35:55.726 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為4000時，10000條共耗時：1050ms
2017-12-17 15:35:55.726 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為5000時，10000條共耗時：805ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為6000時，10000條共耗時：835ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為7000時，10000條共耗時：956ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為8000時，10000條共耗時：776ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為9000時，10000條共耗時：1016ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為10000時，10000條共耗時：1054ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為11000時，10000條共耗時：1172ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為12000時，10000條共耗時：1189ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為13000時，10000條共耗時：1078ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為14000時，10000條共耗時：1780ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為15000時，10000條共耗時：2187ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為16000時，10000條共耗時：2096ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為17000時，10000條共耗時：2072ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為18000時，10000條共耗時：3175ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為19000時，10000條共耗時：3202ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為20000時，10000條共耗時：3065ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為21000時，10000條共耗時：3867ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為22000時，10000條共耗時：2614ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為23000時，10000條共耗時：3466ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為24000時，10000條共耗時：4425ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為25000時，10000條共耗時：5366ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為26000時，10000條共耗時：6404ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為27000時，10000條共耗時：6481ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為28000時，10000條共耗時：5930ms
2017-12-17 15:35:55.810 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為29000時，10000條共耗時：6752ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為30000時，10000條共耗時：5903ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為31000時，10000條共耗時：6539ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為32000時，10000條共耗時：8695ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為33000時，10000條共耗時：8874ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為34000時，10000條共耗時：10502ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為35000時，10000條共耗時：11454ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為36000時，10000條共耗時：11974ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為37000時，10000條共耗時：11695ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為38000時，10000條共耗時：12955ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為39000時，10000條共耗時：13296ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為40000時，10000條共耗時：13684ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為41000時，10000條共耗時：13281ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為42000時，10000條共耗時：14756ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為43000時，10000條共耗時：14626ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為44000時，10000條共耗時：17325ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為45000時，10000條共耗時：18513ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為46000時，10000條共耗時：21122ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為47000時，10000條共耗時：16580ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為48000時，10000條共耗時：15385ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為49000時，10000條共耗時：16923ms
2017-12-17 15:35:55.826 INFO  [main          ] E.EurekaServerApplicationTests [logLength           ]:75   - 長度為50000時，10000條共耗時：17295ms

結論：時間會隨著長度增加而增加

Log4J測試：

效果應該差不多



相關推薦

Logback及Log4J效能測試

分別從兩個方面測試： 1、日誌輸出的格式對效能的影響，即日誌中包含動態方法名和行號對效能的影響 2、日誌長度對效能的影響 LogBack測試 本測試在Spring Boot框架基礎上進行，日誌配置檔案logback-spring.xml內容如下： <?xml ver

redis及叢集效能測試

Redis叢集 文章彙總來源：由Drizzt0878大神提供 1      背景 這段時間專案需要對快取進行叢集，以下通過對redis叢集研究與測試，對比直接採用單機記憶體快取方式的效能。文章記錄本人學習與實踐redis叢集的一些步驟與測試。 2      目標 1

Base64編碼及編碼效能測試

1.什麼是Base64編碼 Base64是一種基於64個可列印字元來表示二進位制資料的表示方法。由於2的6次方等於64，所以每6位為一個單元，對應某個可列印字元。在Base64中的可列印字元包括字母A-Z、a-z、數字0-9，這樣共有62個字元，此外兩個可列印符號在不

Base64編碼及編碼效能測試 [改進]

主要是針對文章中 Base64.cpp中的_getBase64Char(char c)和_getCharOfBase64(char c)兩個函式進行了改進. 原來程式碼為： char _getBase64Char(char c) { if(c>

效能測試工具siege安裝及測試

其實對於類似的效能測試工具之前接觸過AB。我是在ubuntu下做的測試。 1、更新系統 sudo apt-get update && sudo apt-get upgrade –show-upgraded 2、下載最新版的siege wget http://do

效能測試監控平臺：InfluxDB+Grafana+Jmeter linux環境執行jmeter並生成報告 時序資料庫InfluxDB：簡介及安裝 視覺化工具Grafana：簡介及安裝

前面的部落格介紹了InfluxDB、Telegraf、Grafana的安裝和使用方法，這篇部落格，介紹下如何利用這些開源工具搭建效能測試監控平臺。。。   前言 效能測試工具jmeter自帶的監視器對效能測試結果的實時展示，在Windows系統下的GUI模式執行，渲染和效果不是太好，在linu

LoadRunner效能測試之常見函式及引數的說明和作用

lrs_startup(257);  啟動winsocket.dll lrs_create_socket("socket0","TCP","RemoteHost=10.1.106.6:20000",LrsLastArg);   建立socket函式。引數分別是：soc

logback、log4j、log4j2三種日誌框架效能檢測——為什麼用log4j2。

注意：此部落格為轉載 https://blog.csdn.net/yjh1271845364/article/details/70888262 作為記錄 日誌已經成為系統開發中不可或缺的一部分. 但是針對logback, log4j和log4j2. 究竟改如何選擇? 到底效能

Python程式設計實現對2個字串最長的公共子串的多種求解方式，效能測試及優化

解法1-暴力求解法： def LongestCommonSubstring(FirstString,SecondString): ''' 求最長子串解法1： 以字串1的每個漢字作為起始位置 去字串2中找到能與之匹配的最長長度 將這個長度和記錄的最長長度比較

基於滴滴雲之 Netperf 網路效能測試工具的搭建及使用

1. 介紹 Netperf 網路效能是雲伺服器的重要指標，對網路效能進行準確的測試非常重要。Netperf 是一種可以用來衡量不同型別網路效能的工具，它可以用來測試 PPS（即每秒鐘傳送資料包的數目）、單向吞吐量和端到端延遲。本文給大家簡述如何採用 Netperf 對網路效能進行測試。

效能測試過程及模型構建

        在效能測試過程中，建模實際上可分為兩個過程，效能測試過程和模型構建過程，效能測試過程主要完成對系統進行效能測試，並蒐集相應的測試結果，形成測試過程文件；模型構建主要是根據蒐集到的效能測試需求和生產系統的相關資訊完成效能模型的構建工作，並指導

Devops關鍵工具及技術(七)—基於Pipeline的Jmeter效能測試[二](Jmeter效能測試的流水線整合)

有了自動化測試、自然效能測試也需要成為Pipeline的一部分。效能測試的工具常見的主要有Jmeter和LoadRunner。我們將選用Jmeter作為我們的主角。因為它是免費的。由於在Windows下安裝Jmeter比較簡單，所以我們在Jmeter效能測試會

Nodejs操作MongoDB資料庫增刪改查及效能測試

1.Node.js操作MongoDB資料的步驟 安裝MongoDB mkdir koaMongodb cd koaMongodb npm init npm install mongodb --save 引入MongoDB下面的MongoDBClient var M

手機效能測試指標及操作

手機效能測試：啟動時間常用測試方法： 1、程式碼裡插入時間並列印； 2、命令方式： adb shell am start -W -n com.example.hello/.MainActivity -W是指啟動完成之後，返回啟動耗時 -n後面跟的是需要啟動的APP的包名和la

效能測試思路及常遇到的問題分析

開始效能測試前需要了解的內容：1、專案具體需求及測試範圍：測哪些功能，哪些介面,存在哪些場景。2、指標：響應時間，併發數，tps，總tps，穩定性交易總量多少，事務成功率，交易波動範圍，穩定執行時長，資源利用率等3、環境：生產環境伺服器數量，測試環境伺服器數量，按照資源配比得

java實現各種排序演算法(包括氣泡排序，選擇排序，插入排序，快速排序（簡潔版）)及效能測試

1、氣泡排序是排序裡面最簡單的了，但效能也最差，數量小的時候還可以，數量一多，是非常慢的。      它的時間複雜度是O（n*n），空間複雜度是O（1）      程式碼如下，很好理解。 public static void bubbleSort(int[] arr)

Loadrunner 呼叫 Webservice 介面 進行 效能測試 時的方法及優化總結

1.Webservice                       協議指令碼編寫流程 下面介紹使用 Loadrunner 呼叫 Webservice 介面 通用的流程與方法。 1.1 新建指令碼，選擇 "Webservice" 協議 1.2 選擇 Manag

十分簡單的redis使用說明及效能測試

redis相比很多人都知道，是一個記憶體式的key-value資料庫，存取速度極快，使用非常簡單，支援多種語言。本文對其使用進行一個簡要說明，並進行簡單測試。 1.下載與編譯 編譯十分簡單make既可。 2.redis安裝與配置 實際上並不需要安裝。redis

效能測試指標及常用監控工具

監控指標效能測試通常需要監控的指標包括： 1.伺服器Linux（包括CPU、Memory、Load、I/O）。 2.資料庫：1.Mysql 2.Oracle（快取命中、索引、單條SQL效能、資料庫[/url]執行緒數、資料池連線數）。 3.中介軟體：1.Jboss 2. A

效能測試之壓力機瓶頸分析及優化

效能測試過程中，為了給伺服器足夠的壓力，少不了要使用壓力機，即模擬客戶端的機器，壓力機如果使用不當，測試結果就會不準確，反映不了伺服器的真實效能情況。 因此，我們需要充分了解壓力機，並對其進行調優，從而避免壓力機自身瓶頸對壓測帶來影響，為效能測試結果的準確可靠