Peter Firmstone created SUREFIRE-1916: -----------------------------------------
Summary: thread blocked freezing progress Key: SUREFIRE-1916 URL: https://issues.apache.org/jira/browse/SUREFIRE-1916 Project: Maven Surefire Issue Type: Bug Components: Maven Surefire Plugin Affects Versions: 2.22.2 Environment: java -version openjdk version "1.8.0_292" OpenJDK Runtime Environment (Zulu 8.54.0.21-CA-win64) (build 1.8.0_292-b10) OpenJDK 64-Bit Server VM (Zulu 8.54.0.21-CA-win64) (build 25.292-b10, mixed mode) Reporter: Peter Firmstone Thread live lock during reporting. I'll update to a later release, this is very uncommon it's never happened before, just thought I'd report it here. 2021-06-03 12:16:36 Full thread dump OpenJDK 64-Bit Server VM (25.292-b10 mixed mode): "ThreadedStreamConsumer" #40 daemon prio=5 os_prio=0 tid=0x000000003cd46800 nid=0x56b8 runnable [0x000000004118e000] java.lang.Thread.State: RUNNABLE at java.io.FileOutputStream.writeBytes(Native Method) at java.io.FileOutputStream.write(FileOutputStream.java:326) at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82) at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140) - locked <0x00000003f2a966e0> (a java.io.BufferedOutputStream) at java.io.PrintStream.write(PrintStream.java:482) - locked <0x00000003f2a966c0> (a java.io.PrintStream) at sun.nio.cs.StreamEncoder.writeBytes(StreamEncoder.java:221) at sun.nio.cs.StreamEncoder.implFlushBuffer(StreamEncoder.java:291) at sun.nio.cs.StreamEncoder.flushBuffer(StreamEncoder.java:104) - locked <0x00000003f2a96808> (a java.io.OutputStreamWriter) at java.io.OutputStreamWriter.flushBuffer(OutputStreamWriter.java:185) at java.io.PrintStream.write(PrintStream.java:527) - locked <0x00000003f2a966c0> (a java.io.PrintStream) at java.io.PrintStream.print(PrintStream.java:583) at org.fusesource.jansi.FilterPrintStream.write(FilterPrintStream.java:99) at org.fusesource.jansi.FilterPrintStream.write(FilterPrintStream.java:107) at org.fusesource.jansi.FilterPrintStream.print(FilterPrintStream.java:156) at org.fusesource.jansi.FilterPrintStream.println(FilterPrintStream.java:231) - locked <0x00000003f2a70188> (a org.fusesource.jansi.WindowsAnsiPrintStream) at org.slf4j.impl.SimpleLogger.write(SimpleLogger.java:318) at org.slf4j.impl.SimpleLogger.log(SimpleLogger.java:295) at org.slf4j.impl.SimpleLogger.info(SimpleLogger.java:480) at org.apache.maven.cli.logging.Slf4jLogger.info(Slf4jLogger.java:59) at org.apache.maven.plugin.surefire.log.PluginConsoleLogger.info(PluginConsoleLogger.java:77) at org.apache.maven.plugin.surefire.report.ConsoleReporter.println(ConsoleReporter.java:96) at org.apache.maven.plugin.surefire.report.ConsoleReporter.testSetCompleted(ConsoleReporter.java:74) at org.apache.maven.plugin.surefire.report.TestSetRunListener.testSetCompleted(TestSetRunListener.java:183) at org.apache.maven.plugin.surefire.booterclient.output.ForkClient.processLine(ForkClient.java:227) at org.apache.maven.plugin.surefire.booterclient.output.ForkClient.consumeLine(ForkClient.java:177) at org.apache.maven.plugin.surefire.booterclient.output.ThreadedStreamConsumer$Pumper.run(ThreadedStreamConsumer.java:88) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "ping-timer-10s" #39 daemon prio=5 os_prio=0 tid=0x000000003cd49800 nid=0x5c54 waiting on condition [0x0000000040e8f000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x000000067ff54578> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1081) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "timeout-check-timer" #37 daemon prio=5 os_prio=0 tid=0x000000003cd48800 nid=0x67a4 runnable [0x0000000040a6f000] java.lang.Thread.State: TIMED_WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x000000067ff585d0> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2083) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093) at java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "resolver-5" #18 daemon prio=5 os_prio=0 tid=0x000000003a5f9800 nid=0x4894 waiting on condition [0x000000004024f000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000003f2d89448> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "resolver-4" #17 daemon prio=5 os_prio=0 tid=0x000000003a518000 nid=0x59b0 waiting on condition [0x000000004014e000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000003f2d89448> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "resolver-3" #16 daemon prio=5 os_prio=0 tid=0x0000000038426800 nid=0x2944 waiting on condition [0x0000000040d8f000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000003f2d89448> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "resolver-2" #15 daemon prio=5 os_prio=0 tid=0x000000003b423000 nid=0x66b4 waiting on condition [0x0000000040c8e000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000003f2d89448> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "resolver-1" #14 daemon prio=5 os_prio=0 tid=0x000000003ce4d800 nid=0x2c08 waiting on condition [0x0000000040b8f000] java.lang.Thread.State: WAITING (parking) at sun.misc.Unsafe.park(Native Method) - parking to wait for <0x00000003f2d89448> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject) at java.util.concurrent.locks.LockSupport.park(LockSupport.java:175) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2044) at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442) at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1074) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1134) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) at java.lang.Thread.run(Thread.java:748) Locked ownable synchronizers: - None "Service Thread" #10 daemon prio=9 os_prio=0 tid=0x000000003838a000 nid=0x5ef8 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C1 CompilerThread3" #9 daemon prio=9 os_prio=2 tid=0x0000000038304000 nid=0x1484 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C2 CompilerThread2" #8 daemon prio=9 os_prio=2 tid=0x00000000382fd000 nid=0x43b8 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C2 CompilerThread1" #7 daemon prio=9 os_prio=2 tid=0x00000000382f8800 nid=0x5030 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "C2 CompilerThread0" #6 daemon prio=9 os_prio=2 tid=0x00000000382f5000 nid=0x5c74 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Attach Listener" #5 daemon prio=5 os_prio=2 tid=0x00000000382ef000 nid=0x424c waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Signal Dispatcher" #4 daemon prio=9 os_prio=2 tid=0x00000000364ee800 nid=0x46bc runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE Locked ownable synchronizers: - None "Finalizer" #3 daemon prio=8 os_prio=1 tid=0x00000000382e0800 nid=0x22ec in Object.wait() [0x00000000388bf000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000003f2a13aa0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:144) - locked <0x00000003f2a13aa0> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:165) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:216) Locked ownable synchronizers: - None "Reference Handler" #2 daemon prio=10 os_prio=2 tid=0x00000000364db800 nid=0x55ec in Object.wait() [0x00000000387bf000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000003f2a22ec0> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:502) at java.lang.ref.Reference.tryHandlePending(Reference.java:191) - locked <0x00000003f2a22ec0> (a java.lang.ref.Reference$Lock) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153) Locked ownable synchronizers: - None "main" #1 prio=5 os_prio=0 tid=0x0000000002432800 nid=0x6484 waiting for monitor entry [0x00000000023bd000] java.lang.Thread.State: BLOCKED (on object monitor) at org.fusesource.jansi.FilterPrintStream.println(FilterPrintStream.java:231) - waiting to lock <0x00000003f2a70188> (a org.fusesource.jansi.WindowsAnsiPrintStream) at org.slf4j.impl.SimpleLogger.write(SimpleLogger.java:318) at org.slf4j.impl.SimpleLogger.log(SimpleLogger.java:295) at org.slf4j.impl.SimpleLogger.info(SimpleLogger.java:480) at org.apache.maven.cli.logging.Slf4jLogger.info(Slf4jLogger.java:59) at org.apache.maven.plugin.surefire.log.PluginConsoleLogger.info(PluginConsoleLogger.java:77) at org.apache.maven.plugin.surefire.report.DefaultReporterFactory.log(DefaultReporterFactory.java:464) at org.apache.maven.plugin.surefire.report.DefaultReporterFactory.runCompleted(DefaultReporterFactory.java:188) at org.apache.maven.plugin.surefire.report.DefaultReporterFactory.close(DefaultReporterFactory.java:168) at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:251) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1183) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:1011) at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:857) at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:137) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:210) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:156) at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:148) at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:117) at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:81) at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:56) at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:128) at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:305) at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:192) at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:105) at org.apache.maven.cli.MavenCli.execute(MavenCli.java:957) at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:289) at org.apache.maven.cli.MavenCli.main(MavenCli.java:193) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:498) at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:282) at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:225) at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:406) at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:347) Locked ownable synchronizers: - None "VM Thread" os_prio=2 tid=0x00000000364d8800 nid=0x1460 runnable "ParGC Thread#0" os_prio=0 tid=0x0000000002448000 nid=0x2294 runnable "ParGC Thread#1" os_prio=0 tid=0x0000000002449800 nid=0x4c24 runnable "ParGC Thread#2" os_prio=0 tid=0x000000000244b000 nid=0x441c runnable "ParGC Thread#3" os_prio=0 tid=0x000000000244c800 nid=0x22b4 runnable "ParGC Thread#4" os_prio=0 tid=0x0000000002450000 nid=0x65c0 runnable "ParGC Thread#5" os_prio=0 tid=0x0000000002451000 nid=0x5de4 runnable "ParGC Thread#6" os_prio=0 tid=0x0000000002454800 nid=0x4ccc runnable "ParGC Thread#7" os_prio=0 tid=0x0000000002455800 nid=0x32f4 runnable "VM Periodic Task Thread" os_prio=2 tid=0x000000003838c800 nid=0x44dc waiting on condition JNI global references: 505 -- This message was sent by Atlassian Jira (v8.3.4#803005)