程序启动时有一些信息会使用 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 73 天前
文件是不是有其他进程占用呢?
|
2
netizenHan 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 73 天前
你先把 stdout 和 stderr 重定向到文件试试
|
4
0xD800 72 天前 via Android
windows 下的程序? stdout 可能被阻塞了,右键一下控制台
|
5
netizenHan OP |
6
netizenHan OP @netizenHan #5
2. 如果不用 nohup 启动,日志直接正常打印,还是一样阻塞 |