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

Question

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

以下是我的线程转储中的一个 sn-p，我在 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，混合模式，共享）

Ubuntu Linux 服务器双四核机。

写入打印流时似乎锁定了，我考虑过只删除控制台附加程序，但是我宁愿知道它为什么会阻塞并根据这些知识将其删除。在过去，删除并查看它是否有效又回来咬我:)

我的 log4j 中的相关部分

log4j.rootLogger=调试，标准输出 log4j.logger.com.blah=信息，日志 log4j.appender.STDOUT=org.apache.log4j.ConsoleAppender log4j.appender.LOG=org.apache.log4j.FileAppender

线程转储提取

"pool-2-thread-79" Id=149 已阻塞 org.apache.log4j.spi.RootLogger@6c3ba437 由“pool-2-thread-78”拥有，ID=148 在 org.apache.log4j.Category.callAppenders(Category.java:201) 在 org.apache.log4j.Category.forcedLog(Category.java:388) 在 org.apache.log4j.Category.error（Category.java:302） 在 com.blah.MessageProcessTask.run(MessageProcessTask.java:103) 在 java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) 在 java.util.concurrent.FutureTask$Sync.innerRun(FutureTask/java:268) 在 java.util.concurrent.FutureTask.run(FutureTask/java:54) 在 java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) 在 java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) 在 java.lang.Thread.run(Thread.java:619)

"pool-2-thread-78" Id=148 可运行于 java.io.FileOutputStream.writeBytes（本机 方法）在 java.io.FileOutputStream.write(FileOutputStream.java:260) 在 java.io.BufferedOutputStream.write(BufferedOutputStream.java:105) - 锁定 (一个 java.io.BufferedOutputStream) 在 java.io.PrintStream.write(PrintStream.java:430) - 锁定 (a java.io.PrintStream) 在 org.apache.log4j.ConsoleAppender$SystemOutStream.write(ConsoleAppender.java:173) 在 sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:202) 在 sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:272) 在 sun.nio.cs.StreamEncoder.implFlush(StreamEncoder.java:276) 在 sun.nio.cs.StreamEncoder.flush(StreamEncoder.java:122) - 锁定 (一个 java.io.OutputStreamWriter) 在 java.io.OutputStreamWriter.flush(OutputStreamWriter.java:212) 在 org.apache.log4j.helpers.QuietWriter.flush(QuietWriter.java:57) 在 org.apache.log4j.WriterAppender.subAppend(WriterAppender.java:315) 在 org.apache.log4j.WriterAppender.append(WriterAppender.java:159) 在 org.apache.log4j.AppenderSkeleton.doAppend(AppenderSkeleton.java:230) - 锁定 (一个 org.apache.log4j.ConsoleAppender) 在 org.apache.log4j.helpers.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:65) 在 org.apache.log4j.Category.callAppenders(Category.java:203) - 锁定 (一个 org.apache.log4j.spi.RootLogger) 在 org.apache.log4j.Category.forcedLog(Category.java:388) 在 org.apache.log4j.Category.error（Category.java:302） 在 com.blah.MessageProcessTask.run(MessageProcessTask.java:103) 在 java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) 在 java.util.concurrent.FutureTask$Sync.innerRun(FutureTask/java:268) 在 java.util.concurrent.FutureTask.run(FutureTask/java:54) 在 java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:885) 在 java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:907) 在 java.lang.Thread.run(Thread.java:619)

Answer 1

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

在 Windows 上，如果您在控制台窗口中单击，这将暂停控制台，例如标准输出缓冲区将被填满，并且随着控制台附加程序串行写入，您的应用程序将挂起，直到您释放控制台（按 enter 左右）。

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

Answer 2

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

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