Log4j正在挂起我的应用程序，我做错了什么？

multithreading java deadlock log4j

2022-09-04 08:25:08

首先介绍一下应用程序的背景知识。我有一个应用程序通过线程池并行处理许多独立任务。线程池现在挂起。

以下是我的线程转储中的一个片段，我在 pool-2 中的所有线程都被“pool-2-thread-78”阻止了。它似乎被锁定试图写入控制台，我觉得这很奇怪。谁能为我说明一下情况？

编辑：平台详细信息 Java 版本 “1.6.0_07” Java（TM） SE 运行时环境（build 1.6.0_07-b06） Java HotSpot（TM） Client VM （build 10.0-b23， mixed mode， sharing）

Ubuntu Linux 服务器双四核机。

在写入打印流时似乎锁定，我考虑过仅删除控制台附加器，但是我宁愿知道为什么它会阻止并根据此知识将其删除。在过去，删除并查看它是否有效已经回来咬我:)

相关部分来自我的log4j

log4j.rootLogger=DEBUG， STDOUT log4j.logger.com.blah=INFO， LOG log4j.appender.STDOUT=org.apache.log4j.ConsoleAppender log4j.appender.LOG=org.apache.log4j.FileAppender

线程转储提取

“pool-2-thread-79” Id=149 BLOCKED on org.apache.log4j.spi.RootLogger@6c3ba437 by “pool-2-thread-78” Id=148 at org.apache.log4j.Category.callAppenders（Category.java：201） at org.apache.log4j.Category.forcedLog（Category.java：388） at org.apache.log4j.Category.error（Category.java：302） at com.blah.MessageProcessTask.run（MessageProcessTask.java：103） at java.util.concurrent.Executors$RunnableAdapter.call（Executors.java：441） at java.util.concurrent.FutureTask$Sync.innerRun（FutureTask/java：268） at java.util.concurrent.FutureTask.run（FutureTask/java：54） at java.util.concurrent.ThreadPoolExecutor$Worker.runTask（ThreadPoolExecutor.java：885） at java.util.concurrent.ThreadPoolExecutor$Worker.run（ThreadPoolExecutor.java：907） at java.lang.Thread.run（Thread.java：619）

“pool-2-thread-78” Id=148 RUNNABLE at java.io.FileOutputStream.writeBytes（Native Method） at java.io.FileOutputStream.write（FileOutputStream.java：260） at java.io.BufferedOutputStream.write（BufferedOutputStream.java：105） - lock <0x6f314ba4> （a java.io.BufferedOutputStream） at java.io.PrintStream.write（PrintStream.java：430） - 锁定<0xd5d3504> （a java.io.PrintStream） at org.apache.log4j.ConsoleAppender$SystemOutStream.write（ConsoleAppender.java：173） atsun.nio.cs.StreamEncoder.writeBytes（StreamEncoder.java：202） at sun.nio.cs.StreamEncoder.implFlushBuffer（StreamEncoder.java：272） at sun.nio.cs.StreamEncoder.implFlush（StreamEncoder.java：276） at sun.nio.cs.StreamEncoder.flush（StreamEncoder.java：122） - locked <0x6243a076> （a java.io.OutputStreamWriter） at java.io.OutputStreamWriter.flush（OutputStreamWriter.java：212） at org.apache.log4j.helpers.QuietWriter.flush（QuietWriter.java：57） atorg.apache.log4j.WriterAppender.subAppend（WriterAppender.java：315） at org.apache.log4j.WriterAppender.append（WriterAppender.java：159） at org.apache.log4j.AppenderSkeleton.doAppend（AppenderSkeleton.java：230） - lock <0x45dbd560> （a org.apache.log4j.ConsoleAppender） at org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders（AppenderAttachableImpl.java：65） at org.apache.log4j.Category.callAppenders（Category.java：203） - 锁定<0x6c3ba437>（org.apache.log4j.spi.RootLogger） at org.apache.log4j.Category.forcedLog（Category.java：388） at org.apache.log4j.Category.error（Category.java：302） at com.blah.MessageProcessTask.run（MessageProcessTask.java：103） at java.util.concurrent.Executors$RunnableAdapter.call（Executors.java：441） at java.util.concurrent.FutureTask$Sync.innerRun（FutureTask/java：268） at java.util.concurrent.FutureTask.run（FutureTask/java：54） at java.util.concurrent.ThreadPoolExecutor$Worker.runTask（ThreadPoolExecutor.java：885） at java.util.concurrent.ThreadPoolExecutor$Worker.run（ThreadPoolExecutor.java：907） at java.lang.Thread.run（Thread.java：619）

答案 1

您可以使用 AsyncAppender 更好地将记录器与追加器分离。

在 Windows 上，如果在控制台窗口中单击，这将暂停控制台，例如，stdout 缓冲区将填满，并且当控制台追加器串行写入时，您的应用程序将挂起，直到您释放控制台（按 Enter 或类似键）。

考虑将 AsyncAppender 与 log4j 一起使用 - 大多数时候这是个好主意 - 唯一的问题 - AsynAppender 缓冲区在退出时不会完全刷新。

答案 2

首先，我相信log4j会串行写入文件和控制台，否则所有日志都会损坏。因此，当一个线程正在写入时，另一个想要写入的线程必须等到另一个线程完成。此外，如果另一端连接到它的任何东西都没有耗尽它，stdout可以阻止它。

在 unix 中有一个特殊的文件描述符，称为 stdout。当您在控制台中启动应用程序时，标准输出将附加到控制台。您还可以将标准输出重定向到其他文件。例如：java Blah > /dev/null。您可能有标准指向正在填满的文件。例如，管道是一个文件，如果另一端的程序没有排出管道，那么写入管道的程序最终将被阻止。