
程序启动时有一些信息会使用 CONSOLEAPPENDER 打印信息,现在发现有一个日志,日志比较大,12 万字符,打印会卡死在 writeBytes ,然后就不动了,这次是引入了公司的一个 jacoco 覆盖率 agent,但是看起来报错跟这个没太大关系,但是去掉就能正常启动
堆栈信息中并没有其他线程抢占了这个锁,也从系统资源的角度去找问题,但是没有发现什么有价值的信息
"AsyncAppender-Worker-ASYNC_CONSOLE_APPENDER_NAME" #25 daemon prio=5 os_prio=0 tid=0x00007ff4cc698000 nid=0x397 runnable [0x00007ff47475a000] java.lang.Thread.State: RUNNABLE at java.io.FileOutputStream.writeBytes(Native Method) at java.io.FileOutputStream.write(FileOutputStream.java:326) at java.io.BufferedOutputStream.write(BufferedOutputStream.java:122) - locked <0x00000006c00256d0> (a java.io.BufferedOutputStream) at java.io.PrintStream.write(PrintStream.java:480) - locked <0x00000006c00256b0> (a java.io.PrintStream) at java.io.FilterOutputStream.write(FilterOutputStream.java:97) at ch.qos.logback.core.joran.spi.ConsoleTarget$1.write(ConsoleTarget.java:37) at ch.qos.logback.core.OutputStreamAppender.writeBytes(OutputStreamAppender.java:199) at ch.qos.logback.core.OutputStreamAppender.subAppend(OutputStreamAppender.java:231) at ch.qos.logback.core.OutputStreamAppender.append(OutputStreamAppender.java:102) at ch.qos.logback.core.UnsynchronizedAppenderBase.doAppend(UnsynchronizedAppenderBase.java:84) at ch.qos.logback.core.spi.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:51) at ch.qos.logback.core.AsyncAppenderBase$Worker.run(AsyncAppenderBase.java:290)
1 shine1996 2024-09-20 16:20:46 +08:00 文件是不是有其他进程占用呢? |
2 xiaoluxiaolu OP @shine1996 #1 被占用的应该是终端输出,通过 ls -l /proc/834/fd 命令查到了输出的 pipe:l-wx------ 1 root root 64 Sep 20 13:31 1 -> pipe:[3341588393] 然后 lsof | grep 3341588393 ,有一堆信息,dockerini ,bash ,还有自己的 834 java 进程,看起来都比较常规 |
3 sagaxu 2024-09-20 20:32:36 +08:00 你先把 stdout 和 stderr 重定向到文件试试 |
4 0xD800 2024-09-21 12:48:19 +08:00 via Android windows 下的程序? stdout 可能被阻塞了,右键一下控制台 |
5 xiaoluxiaolu OP |
6 xiaoluxiaolu OP @netizenHan #5 2. 如果不用 nohup 启动,日志直接正常打印,还是一样阻塞 |