6

我编写了一个测试程序来验证 logback 相对于 log4j 的性能改进。但令我惊讶的是，我遇到了这个奇怪的问题。我正在使用它们的异步和文件附加程序将一些 200k 日志消息循环写入文件。但是，每次它只记录大约 140k 左右的消息，然后停止。它只是打印我的最后一条日志语句，表明它已将所有内容写入缓冲区并且程序终止。如果我只是用 Log4j 运行相同的程序，我可以在日志文件中看到所有 200k 消息。是否有任何基本的架构差异导致这种情况发生？有没有办法避免呢？我们正在考虑从 log4j 切换到 logback，现在这让我重新思考。

这是我的 logback 配置：

<configuration>
<appender name="STDOUT" class="ch.qos.logback.core.ConsoleAppender">
    <encoder>
        <pattern>%d{HH:mm:ss.SSS} [%thread] %-5level %logger{36} - %msg%n
        </pattern>
    </encoder>
</appender>

<appender name="FILE" class="ch.qos.logback.core.FileAppender">
<file>logback.log</file>
<encoder>
  <pattern>%date %level [%thread] %logger{10} [%file:%line] %msg%n</pattern>
</encoder>
 </appender>

<appender name="ASYNC" class="ch.qos.logback.classic.AsyncAppender">
    <appender-ref ref="FILE" />
</appender>

<root level="info">
    <appender-ref ref="ASYNC" />
</root>
 </configuration>

这是我的代码------

      public static void main(String[] args) throws InterruptedException {
        org.slf4j.Logger logbackLogger = LoggerFactory
                .getLogger(LogbackTest.class);

        List<Integer> runs = Arrays.asList(1000, 5000, 50000, 200000);
        ArrayList<Long> logbackRuntimes = new ArrayList<>(4);

        for (int run = 0; run < runs.size(); run++) {
            logbackLogger.info("------------------------>Starting run: "
                    + (run + 1));
            // logback test
            long stTime = System.nanoTime();
            int i = 0;
            for (i = 1; i <= runs.get(run); i++) {
                Thread.sleep(1);
                logbackLogger
                .info("This is a Logback test log, run: {},     iter: {}",
                                run, i);
            }
            logbackRuntimes.add(System.nanoTime() - stTime);
            logbackLogger.info("logback run - " + (run + 1) + " " + i);
        }
        Thread.sleep(5000);
        // print results
        logbackLogger.info("Run times:");
        logbackLogger
            .info("Run\tNoOfMessages\tLog4j Time(ms)\tLogback Time(ms)");
        for (int run = 0; run < runs.size(); run++) {
            logbackLogger.info((run + 1) + "\t" + runs.get(run) + "\t"
                    + logbackRuntimes.get(run) / 10e6d);
        }
    }
4

1 回答 1

8

根据文档：

[...] 默认情况下，当队列容量不足 20% 时，AsyncAppender 将丢弃 TRACE、DEBUG 和 INFO 级别的事件，仅保留 WARN 和 ERROR 级别的事件。当队列容量小于 20% 时，此策略以丢失级别 TRACE、DEBUG 和 INFO 的事件为代价，确保日志事件的非阻塞处理（因此具有出色的性能）。可以通过将 discardingThreshold 属性设置为 0（零）来防止事件丢失。

于 2014-01-21T08:22:01.667 回答