Einführung in die Methode des asynchronen Druckens von Logback-Protokollen (Codebeispiel)

Dieser Artikel bietet Ihnen eine Einführung in die Methode des asynchronen Druckens von Logback-Protokollen (Codebeispiele). Ich hoffe, dass er Ihnen als Referenz dienen wird.

Kürzlich ist ein Problem aufgetreten: Das Serverprogramm des Kunden reagiert gelegentlich zu langsam auf Anfragen. Bei der Überprüfung des Protokolls wurde festgestellt, dass der Code für die RSA-Verifizierungssignatur länger als 20 Sekunden ausgeführt wurde , während es unter normalen Umständen nur 16 ms erfordert.

Das RSA-Zertifikat wird geladen, wenn der Server startet, sodass es kein Problem mit langsamem Dateilesen gibt. Nachdem ich mir diese Codezeilen angesehen habe, ist der Code zum Drucken des Logback-Protokolls das Verdächtigste.

Überprüfte die Konfiguration des Produktionsprotokolls. Jede Datei ist mit dem Suffix *.log.zip konfiguriert und die Größe beträgt 10 MB, was bedeutet, dass 10 MB eine größenbegrenzte Datei ist. Die Protokolldruckklasse wird mit RollingFileAppender konfiguriert.

Am 30.01.2019 wurden mehr als 5.000 Protokolldateien im Ordner „Januar 2019“ generiert, mit einem Durchschnitt von 167 Protokolldateien pro Tag.

Es besteht also der Verdacht, dass zu viele Protokolldateien vorhanden sind und die Zeit zum Indizieren der Dateien zu lang ist, was zu einer Verlangsamung der Codeausführung führt. Mit anderen Worten: Wenn Sie daran zweifeln möchten, ist dies der Fall Eine Voraussetzung: Der Protokolldruck erfolgt synchron und das Druckprotokoll wird aufgerufen. Nachdem die Codezeile ausgeführt und in die Datei geschrieben wurde, wird der Geschäftscode weiterhin ausgeführt.

Ich habe mehrere Kollegen gefragt und alle haben mir gesagt, dass der Protokolldruck asynchron erfolgt. Nur ein Freund sagte, dass er synchron sei. Wir alle denken, dass das Drucken von Protokollen und das Schreiben von Dateien Zeit braucht und das Protokollierungsframework Dateien nicht synchron schreiben sollte. Tatsache ist jedoch, dass das Protokoll synchron gedruckt wird, wenn das asynchrone Drucken nicht konfiguriert ist.

Der Haltepunkt-Folgecode hat festgestellt, dass das Protokoll synchron in die Datei geschrieben wird. Nur wenn der konfigurierte Appender AsyncAppender ist, wird der Protokolldruck asynchron gedruckt.

Das Folgende ist die Situation beim synchronen Drucken von Protokollen:

logback.xml-Konfiguration:

<appender name="CONSOLE" class="ch.qos.logback.core.ConsoleAppender">
    ......
</appender>

<root level="DEBUG">
    <appender-ref ref="CONSOLE"/>
</root>

Ausführungscode:

LogWork.debug("111111111111");
System.out.println("2222222222222");

Laufergebnis:

Wie Sie sehen können, ist das Ausführungsergebnis das Protokoll des Das Protokoll-Framework wird zuerst gedruckt und ausgeführt. Anschließend wird der nachfolgende Geschäftscode ausgeführt. Es ist also synchron.

Im Folgenden erfahren Sie, wie Sie die Protokollklasse für den asynchronen Druck konfigurieren:

Die Protokollklasse für den asynchronen Druck AsyncAppender muss auf eine andere Protokolldruckklasse verweisen, und ASYNC muss nur drucken Schreiben Sie das zu druckende Protokoll in die definierte Cache-Warteschlange und starten Sie dann einen Daemon-Thread, um das Protokoll aus der Warteschlange abzurufen, und rufen Sie den CONSOLE-Protokolldrucker auf, um die Datei zu schreiben. Auf diese Weise erfolgt der Protokolldruck asynchron.

logback.xml-Konfiguration:

<appender name="CONSOLE" class="ch.qos.logback.core.ConsoleAppender">
    ......
</appender>

<appender name="ASYNC" class="ch.qos.logback.classic.AsyncAppender">
    <discardingThreshold>0</discardingThreshold>
    <queueSize>100</queueSize>
    <neverBlock>true</neverBlock>
    <appender-ref ref="CONSOLE"/>
</appender>

<root level="INFO">
    <appender-ref ref="ASYNC"/>
</root>

Ausführungscode:

LogWork.debug("111111111111");
System.out.println("2222222222222");

Ausführungsergebnisse:

Die Ausführungsergebnisse zeigen, dass nach der Ausführung des Codes, der das Protokoll-Framework zum Drucken aufruft, das zu druckende Protokoll einfach in die Cache-Warteschlange gestellt wird , und führt dann den Rest weiter aus, sodass zuerst 222222 und dann 111111 gedruckt werden. Die Erklärung liegt darin, dass der Protokolldruck nun so konfiguriert ist, dass er asynchron funktioniert.

Da die Frage am Anfang des Artikels das Problem der Synchronisierung des Protokolldrucks aufwarf, habe ich einige Nachforschungen angestellt, aber am Ende habe ich es nicht mit dem aufgetretenen Problem in Verbindung gebracht, denn selbst wenn ich wüsste, dass das Das Protokoll wurde synchron gedruckt. Es gibt keine Möglichkeit zu erklären, warum die Codeausführung gelegentlich einige Minuten lang extrem langsam ist. Die Beweise sind unzureichend, sodass keine Schlussfolgerung gezogen werden kann.

Das obige ist der detaillierte Inhalt vonEinführung in die Methode des asynchronen Druckens von Logback-Protokollen (Codebeispiel). Für weitere Informationen folgen Sie bitte anderen verwandten Artikeln auf der PHP chinesischen Website!