[ https://issues.apache.org/jira/browse/SUREFIRE-1631?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16971522#comment-16971522 ]
Adolfo Cia edited comment on SUREFIRE-1631 at 11/11/19 1:21 PM: ---------------------------------------------------------------- [~tibordigana] Ok, snapshot 3.0.0 didn't work (failed the fifth time I execute it): {code:java} [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 9.047 s [INFO] Finished at: 2019-11-11T10:15:47-03:00 [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:3.0.0-SNAPSHOT:test (default-test) on project ltl-java-linehauloperations-ensemble: There are test failures. [ERROR] [ERROR] Please refer to C:\liberty_development\workspaces\services\ltl-java-linehauloperations-ensemble\target\surefire-reports for the individual test results. [ERROR] Please refer to dump files (if any exist) [date].dump, [date]-jvmRun[N].dump and [date].dumpstream. [ERROR] The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was cmd.exe /X /C "C:\liberty_development\wlp\java\java\jre\bin\java -javaagent:C:\Users\adolfo.cia\.m2\repository/org/jmockit/jmockit/1.48/jmockit-1.48.jar -jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174\surefirebooter94309476510590017.jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174 2019-11-11T10-15-44_326-jvmRun1 surefire4984701455985067123tmp surefire_01067224024401515361tmp" [ERROR] Error occurred in starting fork, check output in log [ERROR] Process Exit Code: 1 [ERROR] Crashed tests: [ERROR] com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest [ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was cmd.exe /X /C "C:\liberty_development\wlp\java\java\jre\bin\java -javaagent:C:\Users\adolfo.cia\.m2\repository/org/jmockit/jmockit/1.48/jmockit-1.48.jar -jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174\surefirebooter94309476510590017.jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174 2019-11-11T10-15-44_326-jvmRun1 surefire4984701455985067123tmp surefire_01067224024401515361tmp" [ERROR] Error occurred in starting fork, check output in log [ERROR] Process Exit Code: 1 [ERROR] Crashed tests: [ERROR] com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.fork(ForkStarter.java:690) [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:285) [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:248) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1213) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:1059) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:886) [ERROR] at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:137) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:210) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:156) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:148) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:117) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:81) [ERROR] at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:56) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:128) [ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:305) [ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:192) [ERROR] at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:105) [ERROR] at org.apache.maven.cli.MavenCli.execute(MavenCli.java:956) [ERROR] at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:288) [ERROR] at org.apache.maven.cli.MavenCli.main(MavenCli.java:192) [ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:90) [ERROR] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) [ERROR] at java.lang.reflect.Method.invoke(Method.java:508) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:282) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:225) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:406) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:347) [ERROR] [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoExecutionException {code} and the dump file: {code:java} # Created at 2019-11-11T10:15:47.309 Killing self fork JVM. Maven process died. Thread dump before killing the process (21660@LAP0462): "main" java.lang.Thread.State: RUNNABLE at java.util.zip.ZipFile.getEntryByModifiedUTF8(Native Method) at java.util.zip.ZipFile.getEntry(ZipFile.java:353) at java.util.jar.JarFile.getEntry(JarFile.java:316) at java.util.jar.JarFile.getJarEntry(JarFile.java:299) at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:1184) at sun.misc.URLClassPath.getResource(URLClassPath.java:377) at java.net.URLClassLoader$ClassFinder.run(URLClassLoader.java:1185) at java.security.AccessController.doPrivileged(AccessController.java:739) at java.net.URLClassLoader.findClass(URLClassLoader.java:605) at java.lang.ClassLoader.loadClassHelper(ClassLoader.java:925) at java.lang.ClassLoader.loadClass(ClassLoader.java:870) at java.lang.ClassLoader.loadClassHelper(ClassLoader.java:914) at java.lang.ClassLoader.loadClass(ClassLoader.java:870) at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:343) at java.lang.ClassLoader.loadClass(ClassLoader.java:853) at org.apache.logging.log4j.core.util.Loader.loadClass(Loader.java:241) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.loadClass(ThrowableProxyHelper.java:229) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.loadClass(ThrowableProxyHelper.java:220) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.toExtendedStackTrace(ThrowableProxyHelper.java:114) at org.apache.logging.log4j.core.impl.ThrowableProxy.<init>(ThrowableProxy.java:109) at org.apache.logging.log4j.core.impl.ThrowableProxy.<init>(ThrowableProxy.java:93) at org.apache.logging.log4j.core.impl.Log4jLogEvent.getThrownProxy(Log4jLogEvent.java:605) at org.apache.logging.log4j.core.pattern.ExtendedThrowablePatternConverter.format(ExtendedThrowablePatternConverter.java:63) at org.apache.logging.log4j.core.pattern.PatternFormatter.format(PatternFormatter.java:38) at org.apache.logging.log4j.core.layout.PatternLayout$PatternSerializer.toSerializable(PatternLayout.java:334) at org.apache.logging.log4j.core.layout.PatternLayout.toText(PatternLayout.java:233) at org.apache.logging.log4j.core.layout.PatternLayout.encode(PatternLayout.java:218) at org.apache.logging.log4j.core.layout.PatternLayout.encode(PatternLayout.java:58) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.directEncodeEvent(AbstractOutputStreamAppender.java:197) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.tryAppend(AbstractOutputStreamAppender.java:190) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.append(AbstractOutputStreamAppender.java:181) at org.apache.logging.log4j.core.config.AppenderControl.tryCallAppender(AppenderControl.java:156) at org.apache.logging.log4j.core.config.AppenderControl.callAppender0(AppenderControl.java:129) at org.apache.logging.log4j.core.config.AppenderControl.callAppenderPreventRecursion(AppenderControl.java:120) at org.apache.logging.log4j.core.config.AppenderControl.callAppender(AppenderControl.java:84) at org.apache.logging.log4j.core.config.LoggerConfig.callAppenders(LoggerConfig.java:464) at org.apache.logging.log4j.core.config.LoggerConfig.processLogEvent(LoggerConfig.java:448) at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:431) at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:406) at org.apache.logging.log4j.core.config.DefaultReliabilityStrategy.log(DefaultReliabilityStrategy.java:49) at org.apache.logging.log4j.core.Logger.logMessage(Logger.java:146) at org.apache.logging.log4j.spi.AbstractLogger.tryLogMessage(AbstractLogger.java:2170) at org.apache.logging.log4j.spi.AbstractLogger.logMessageTrackRecursion(AbstractLogger.java:2125) at org.apache.logging.log4j.spi.AbstractLogger.logMessageSafely(AbstractLogger.java:2108) at org.apache.logging.log4j.spi.AbstractLogger.logMessage(AbstractLogger.java:2002) at org.apache.logging.log4j.spi.AbstractLogger.logIfEnabled(AbstractLogger.java:1974) at org.apache.logging.log4j.spi.AbstractLogger.error(AbstractLogger.java:741) at com.xpo.ltl.api.exception.AbstractExceptionBuilder.build(AbstractExceptionBuilder.java:236) at com.xpo.ltl.api.exception.AbstractApiExceptionBuilder.build(AbstractApiExceptionBuilder.java:21) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.throwExceptionForMissingArchivedDocument(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.getByteCodeForDocument(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.execute(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest.lambda$testExecute_shouldCreateResponseWithBadTokenAndNullTokenRetryResponse$4(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest$$Lambda$300.000000001382A370.execute(Unknown Source) at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:55) at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:37) at org.junit.jupiter.api.Assertions.assertThrows(Assertions.java:2952) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest.testExecute_shouldCreateResponseWithBadTokenAndNullTokenRetryResponse(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:90) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) at java.lang.reflect.Method.invoke(Method.java:508) at org.junit.platform.commons.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:675) at org.junit.jupiter.engine.execution.MethodInvocation.proceed(MethodInvocation.java:60) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$ValidatingInvocation.proceed(InvocationInterceptorChain.java:125) at org.junit.jupiter.engine.extension.TimeoutExtension.intercept(TimeoutExtension.java:132) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestableMethod(TimeoutExtension.java:124) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestMethod(TimeoutExtension.java:74) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda$117.0000000011C453B0.apply(Unknown Source) at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall.lambda$ofVoidMethod$0(ExecutableInvoker.java:115) at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall$$Lambda$118.0000000011C48150.apply(Unknown Source) at org.junit.jupiter.engine.execution.ExecutableInvoker.lambda$invoke$0(ExecutableInvoker.java:105) at org.junit.jupiter.engine.execution.ExecutableInvoker$$Lambda$246.0000000011CEC8D0.apply(Unknown Source) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$InterceptedInvocation.proceed(InvocationInterceptorChain.java:104) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.proceed(InvocationInterceptorChain.java:62) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.chainAndInvoke(InvocationInterceptorChain.java:43) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.invoke(InvocationInterceptorChain.java:35) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:104) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:98) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$6(TestMethodTestDescriptor.java:202) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda$275.0000000011D9CD40.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:198) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:135) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:69) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$190.0000000011CCD330.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$189.0000000011CCCEB0.invoke(Unknown Source) at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$188.0000000011CCCA50.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122) at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService$$Lambda$194.0000000011CCE460.accept(Unknown Source) at java.util.ArrayList.forEach(ArrayList.java:1268) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139)"JIT Compilation Thread-0" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-1 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-2 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-3 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-4 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-5 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-6 Suspended" java.lang.Thread.State: RUNNABLE"JIT Diagnostic Compilation Thread-7 Suspended" java.lang.Thread.State: RUNNABLE"JIT-SamplerThread" java.lang.Thread.State: TIMED_WAITING"IProfiler" java.lang.Thread.State: RUNNABLE"Signal Dispatcher" java.lang.Thread.State: RUNNABLE at com.ibm.misc.SignalDispatcher.waitForSignal(Native Method) at com.ibm.misc.SignalDispatcher.run(SignalDispatcher.java:73)"Concurrent Mark Helper" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"Finalizer thread" java.lang.Thread.State: RUNNABLE"Attach API wait loop" java.lang.Thread.State: RUNNABLE at com.ibm.tools.attach.target.IPC.waitSemaphore(Native Method) at com.ibm.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:254) at com.ibm.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66) at com.ibm.tools.attach.target.WaitLoop.run(WaitLoop.java:154)"surefire-forkedjvm-command-thread" java.lang.Thread.State: RUNNABLE at java.io.FileInputStream.readBytes(Native Method) at java.io.FileInputStream.read(FileInputStream.java:272) at java.io.BufferedInputStream.fill(BufferedInputStream.java:257) at java.io.BufferedInputStream.read1(BufferedInputStream.java:297) at java.io.BufferedInputStream.read(BufferedInputStream.java:356) at com.ibm.jvm.io.ConsoleInputStream.read(ConsoleInputStream.java:245) at java.io.DataInputStream.readFully(DataInputStream.java:206) at java.io.DataInputStream.readInt(DataInputStream.java:398) at org.apache.maven.surefire.booter.MasterProcessCommand.decode(MasterProcessCommand.java:113) at org.apache.maven.surefire.booter.CommandReader$CommandRunnable.run(CommandReader.java:383) at java.lang.Thread.run(Thread.java:812)"surefire-forkedjvm-ping-30s" java.lang.Thread.State: RUNNABLE at com.ibm.java.lang.management.internal.ThreadMXBeanImpl.getMultiThreadInfoImpl(Native Method) at com.ibm.java.lang.management.internal.ThreadMXBeanImpl.getThreadInfo(ThreadMXBeanImpl.java:257) at org.apache.maven.surefire.booter.ForkedBooter.generateThreadDump(ForkedBooter.java:542) at org.apache.maven.surefire.booter.ForkedBooter.access$400(ForkedBooter.java:65) at org.apache.maven.surefire.booter.ForkedBooter$2.run(ForkedBooter.java:232) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:522) at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:319) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:191) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1160) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.lang.Thread.run(Thread.java:812)"MemoryMXBean notification dispatcher" java.lang.Thread.State: RUNNABLE at com.ibm.lang.management.internal.MemoryNotificationThread.processNotificationLoop(Native Method) at com.ibm.lang.management.internal.MemoryNotificationThread.run(MemoryNotificationThread.java:183) {code} was (Author: adolfo.cia): [~tibordigana] Ok, snapshot 3.0.0 didn't work: {code:java} [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 9.047 s [INFO] Finished at: 2019-11-11T10:15:47-03:00 [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:3.0.0-SNAPSHOT:test (default-test) on project ltl-java-linehauloperations-ensemble: There are test failures. [ERROR] [ERROR] Please refer to C:\liberty_development\workspaces\services\ltl-java-linehauloperations-ensemble\target\surefire-reports for the individual test results. [ERROR] Please refer to dump files (if any exist) [date].dump, [date]-jvmRun[N].dump and [date].dumpstream. [ERROR] The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was cmd.exe /X /C "C:\liberty_development\wlp\java\java\jre\bin\java -javaagent:C:\Users\adolfo.cia\.m2\repository/org/jmockit/jmockit/1.48/jmockit-1.48.jar -jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174\surefirebooter94309476510590017.jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174 2019-11-11T10-15-44_326-jvmRun1 surefire4984701455985067123tmp surefire_01067224024401515361tmp" [ERROR] Error occurred in starting fork, check output in log [ERROR] Process Exit Code: 1 [ERROR] Crashed tests: [ERROR] com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest [ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called? [ERROR] Command was cmd.exe /X /C "C:\liberty_development\wlp\java\java\jre\bin\java -javaagent:C:\Users\adolfo.cia\.m2\repository/org/jmockit/jmockit/1.48/jmockit-1.48.jar -jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174\surefirebooter94309476510590017.jar C:\Users\adolfo.cia\AppData\Local\Temp\surefire1699052172262397174 2019-11-11T10-15-44_326-jvmRun1 surefire4984701455985067123tmp surefire_01067224024401515361tmp" [ERROR] Error occurred in starting fork, check output in log [ERROR] Process Exit Code: 1 [ERROR] Crashed tests: [ERROR] com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.fork(ForkStarter.java:690) [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:285) [ERROR] at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:248) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1213) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:1059) [ERROR] at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:886) [ERROR] at org.apache.maven.plugin.DefaultBuildPluginManager.executeMojo(DefaultBuildPluginManager.java:137) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:210) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:156) [ERROR] at org.apache.maven.lifecycle.internal.MojoExecutor.execute(MojoExecutor.java:148) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:117) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleModuleBuilder.buildProject(LifecycleModuleBuilder.java:81) [ERROR] at org.apache.maven.lifecycle.internal.builder.singlethreaded.SingleThreadedBuilder.build(SingleThreadedBuilder.java:56) [ERROR] at org.apache.maven.lifecycle.internal.LifecycleStarter.execute(LifecycleStarter.java:128) [ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:305) [ERROR] at org.apache.maven.DefaultMaven.doExecute(DefaultMaven.java:192) [ERROR] at org.apache.maven.DefaultMaven.execute(DefaultMaven.java:105) [ERROR] at org.apache.maven.cli.MavenCli.execute(MavenCli.java:956) [ERROR] at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:288) [ERROR] at org.apache.maven.cli.MavenCli.main(MavenCli.java:192) [ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [ERROR] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:90) [ERROR] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) [ERROR] at java.lang.reflect.Method.invoke(Method.java:508) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launchEnhanced(Launcher.java:282) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.launch(Launcher.java:225) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.mainWithExitCode(Launcher.java:406) [ERROR] at org.codehaus.plexus.classworlds.launcher.Launcher.main(Launcher.java:347) [ERROR] [ERROR] -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoExecutionException {code} and the dump file: {code:java} # Created at 2019-11-11T10:15:47.309 Killing self fork JVM. Maven process died. Thread dump before killing the process (21660@LAP0462): "main" java.lang.Thread.State: RUNNABLE at java.util.zip.ZipFile.getEntryByModifiedUTF8(Native Method) at java.util.zip.ZipFile.getEntry(ZipFile.java:353) at java.util.jar.JarFile.getEntry(JarFile.java:316) at java.util.jar.JarFile.getJarEntry(JarFile.java:299) at sun.misc.URLClassPath$JarLoader.getResource(URLClassPath.java:1184) at sun.misc.URLClassPath.getResource(URLClassPath.java:377) at java.net.URLClassLoader$ClassFinder.run(URLClassLoader.java:1185) at java.security.AccessController.doPrivileged(AccessController.java:739) at java.net.URLClassLoader.findClass(URLClassLoader.java:605) at java.lang.ClassLoader.loadClassHelper(ClassLoader.java:925) at java.lang.ClassLoader.loadClass(ClassLoader.java:870) at java.lang.ClassLoader.loadClassHelper(ClassLoader.java:914) at java.lang.ClassLoader.loadClass(ClassLoader.java:870) at sun.misc.Launcher$AppClassLoader.loadClass(Launcher.java:343) at java.lang.ClassLoader.loadClass(ClassLoader.java:853) at org.apache.logging.log4j.core.util.Loader.loadClass(Loader.java:241) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.loadClass(ThrowableProxyHelper.java:229) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.loadClass(ThrowableProxyHelper.java:220) at org.apache.logging.log4j.core.impl.ThrowableProxyHelper.toExtendedStackTrace(ThrowableProxyHelper.java:114) at org.apache.logging.log4j.core.impl.ThrowableProxy.<init>(ThrowableProxy.java:109) at org.apache.logging.log4j.core.impl.ThrowableProxy.<init>(ThrowableProxy.java:93) at org.apache.logging.log4j.core.impl.Log4jLogEvent.getThrownProxy(Log4jLogEvent.java:605) at org.apache.logging.log4j.core.pattern.ExtendedThrowablePatternConverter.format(ExtendedThrowablePatternConverter.java:63) at org.apache.logging.log4j.core.pattern.PatternFormatter.format(PatternFormatter.java:38) at org.apache.logging.log4j.core.layout.PatternLayout$PatternSerializer.toSerializable(PatternLayout.java:334) at org.apache.logging.log4j.core.layout.PatternLayout.toText(PatternLayout.java:233) at org.apache.logging.log4j.core.layout.PatternLayout.encode(PatternLayout.java:218) at org.apache.logging.log4j.core.layout.PatternLayout.encode(PatternLayout.java:58) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.directEncodeEvent(AbstractOutputStreamAppender.java:197) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.tryAppend(AbstractOutputStreamAppender.java:190) at org.apache.logging.log4j.core.appender.AbstractOutputStreamAppender.append(AbstractOutputStreamAppender.java:181) at org.apache.logging.log4j.core.config.AppenderControl.tryCallAppender(AppenderControl.java:156) at org.apache.logging.log4j.core.config.AppenderControl.callAppender0(AppenderControl.java:129) at org.apache.logging.log4j.core.config.AppenderControl.callAppenderPreventRecursion(AppenderControl.java:120) at org.apache.logging.log4j.core.config.AppenderControl.callAppender(AppenderControl.java:84) at org.apache.logging.log4j.core.config.LoggerConfig.callAppenders(LoggerConfig.java:464) at org.apache.logging.log4j.core.config.LoggerConfig.processLogEvent(LoggerConfig.java:448) at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:431) at org.apache.logging.log4j.core.config.LoggerConfig.log(LoggerConfig.java:406) at org.apache.logging.log4j.core.config.DefaultReliabilityStrategy.log(DefaultReliabilityStrategy.java:49) at org.apache.logging.log4j.core.Logger.logMessage(Logger.java:146) at org.apache.logging.log4j.spi.AbstractLogger.tryLogMessage(AbstractLogger.java:2170) at org.apache.logging.log4j.spi.AbstractLogger.logMessageTrackRecursion(AbstractLogger.java:2125) at org.apache.logging.log4j.spi.AbstractLogger.logMessageSafely(AbstractLogger.java:2108) at org.apache.logging.log4j.spi.AbstractLogger.logMessage(AbstractLogger.java:2002) at org.apache.logging.log4j.spi.AbstractLogger.logIfEnabled(AbstractLogger.java:1974) at org.apache.logging.log4j.spi.AbstractLogger.error(AbstractLogger.java:741) at com.xpo.ltl.api.exception.AbstractExceptionBuilder.build(AbstractExceptionBuilder.java:236) at com.xpo.ltl.api.exception.AbstractApiExceptionBuilder.build(AbstractApiExceptionBuilder.java:21) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.throwExceptionForMissingArchivedDocument(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.getByteCodeForDocument(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbNailMinuet.execute(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest.lambda$testExecute_shouldCreateResponseWithBadTokenAndNullTokenRetryResponse$4(Unknown Source) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest$$Lambda$300.000000001382A370.execute(Unknown Source) at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:55) at org.junit.jupiter.api.AssertThrows.assertThrows(AssertThrows.java:37) at org.junit.jupiter.api.Assertions.assertThrows(Assertions.java:2952) at com.xpo.ltl.linehauloperations.service.ejb.v1.GetImageThumbnailMinuetTest.testExecute_shouldCreateResponseWithBadTokenAndNullTokenRetryResponse(Unknown Source) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:90) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:55) at java.lang.reflect.Method.invoke(Method.java:508) at org.junit.platform.commons.util.ReflectionUtils.invokeMethod(ReflectionUtils.java:675) at org.junit.jupiter.engine.execution.MethodInvocation.proceed(MethodInvocation.java:60) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$ValidatingInvocation.proceed(InvocationInterceptorChain.java:125) at org.junit.jupiter.engine.extension.TimeoutExtension.intercept(TimeoutExtension.java:132) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestableMethod(TimeoutExtension.java:124) at org.junit.jupiter.engine.extension.TimeoutExtension.interceptTestMethod(TimeoutExtension.java:74) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda$117.0000000011C453B0.apply(Unknown Source) at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall.lambda$ofVoidMethod$0(ExecutableInvoker.java:115) at org.junit.jupiter.engine.execution.ExecutableInvoker$ReflectiveInterceptorCall$$Lambda$118.0000000011C48150.apply(Unknown Source) at org.junit.jupiter.engine.execution.ExecutableInvoker.lambda$invoke$0(ExecutableInvoker.java:105) at org.junit.jupiter.engine.execution.ExecutableInvoker$$Lambda$246.0000000011CEC8D0.apply(Unknown Source) at org.junit.jupiter.engine.execution.InvocationInterceptorChain$InterceptedInvocation.proceed(InvocationInterceptorChain.java:104) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.proceed(InvocationInterceptorChain.java:62) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.chainAndInvoke(InvocationInterceptorChain.java:43) at org.junit.jupiter.engine.execution.InvocationInterceptorChain.invoke(InvocationInterceptorChain.java:35) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:104) at org.junit.jupiter.engine.execution.ExecutableInvoker.invoke(ExecutableInvoker.java:98) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.lambda$invokeTestMethod$6(TestMethodTestDescriptor.java:202) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor$$Lambda$275.0000000011D9CD40.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.invokeTestMethod(TestMethodTestDescriptor.java:198) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:135) at org.junit.jupiter.engine.descriptor.TestMethodTestDescriptor.execute(TestMethodTestDescriptor.java:69) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$190.0000000011CCD330.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$7(NodeTestTask.java:125) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$189.0000000011CCCEB0.invoke(Unknown Source) at org.junit.platform.engine.support.hierarchical.Node.around(Node.java:135) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$8(NodeTestTask.java:123) at org.junit.platform.engine.support.hierarchical.NodeTestTask$$Lambda$188.0000000011CCCA50.execute(Unknown Source) at org.junit.platform.engine.support.hierarchical.ThrowableCollector.execute(ThrowableCollector.java:73) at org.junit.platform.engine.support.hierarchical.NodeTestTask.executeRecursively(NodeTestTask.java:122) at org.junit.platform.engine.support.hierarchical.NodeTestTask.execute(NodeTestTask.java:80) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService$$Lambda$194.0000000011CCE460.accept(Unknown Source) at java.util.ArrayList.forEach(ArrayList.java:1268) at org.junit.platform.engine.support.hierarchical.SameThreadHierarchicalTestExecutorService.invokeAll(SameThreadHierarchicalTestExecutorService.java:38) at org.junit.platform.engine.support.hierarchical.NodeTestTask.lambda$executeRecursively$5(NodeTestTask.java:139)"JIT Compilation Thread-0" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-1 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-2 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-3 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-4 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-5 Suspended" java.lang.Thread.State: RUNNABLE"JIT Compilation Thread-6 Suspended" java.lang.Thread.State: RUNNABLE"JIT Diagnostic Compilation Thread-7 Suspended" java.lang.Thread.State: RUNNABLE"JIT-SamplerThread" java.lang.Thread.State: TIMED_WAITING"IProfiler" java.lang.Thread.State: RUNNABLE"Signal Dispatcher" java.lang.Thread.State: RUNNABLE at com.ibm.misc.SignalDispatcher.waitForSignal(Native Method) at com.ibm.misc.SignalDispatcher.run(SignalDispatcher.java:73)"Concurrent Mark Helper" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"GC Slave" java.lang.Thread.State: RUNNABLE"Finalizer thread" java.lang.Thread.State: RUNNABLE"Attach API wait loop" java.lang.Thread.State: RUNNABLE at com.ibm.tools.attach.target.IPC.waitSemaphore(Native Method) at com.ibm.tools.attach.target.CommonDirectory.waitSemaphore(CommonDirectory.java:254) at com.ibm.tools.attach.target.WaitLoop.waitForNotification(WaitLoop.java:66) at com.ibm.tools.attach.target.WaitLoop.run(WaitLoop.java:154)"surefire-forkedjvm-command-thread" java.lang.Thread.State: RUNNABLE at java.io.FileInputStream.readBytes(Native Method) at java.io.FileInputStream.read(FileInputStream.java:272) at java.io.BufferedInputStream.fill(BufferedInputStream.java:257) at java.io.BufferedInputStream.read1(BufferedInputStream.java:297) at java.io.BufferedInputStream.read(BufferedInputStream.java:356) at com.ibm.jvm.io.ConsoleInputStream.read(ConsoleInputStream.java:245) at java.io.DataInputStream.readFully(DataInputStream.java:206) at java.io.DataInputStream.readInt(DataInputStream.java:398) at org.apache.maven.surefire.booter.MasterProcessCommand.decode(MasterProcessCommand.java:113) at org.apache.maven.surefire.booter.CommandReader$CommandRunnable.run(CommandReader.java:383) at java.lang.Thread.run(Thread.java:812)"surefire-forkedjvm-ping-30s" java.lang.Thread.State: RUNNABLE at com.ibm.java.lang.management.internal.ThreadMXBeanImpl.getMultiThreadInfoImpl(Native Method) at com.ibm.java.lang.management.internal.ThreadMXBeanImpl.getThreadInfo(ThreadMXBeanImpl.java:257) at org.apache.maven.surefire.booter.ForkedBooter.generateThreadDump(ForkedBooter.java:542) at org.apache.maven.surefire.booter.ForkedBooter.access$400(ForkedBooter.java:65) at org.apache.maven.surefire.booter.ForkedBooter$2.run(ForkedBooter.java:232) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:522) at java.util.concurrent.FutureTask.runAndReset(FutureTask.java:319) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$301(ScheduledThreadPoolExecutor.java:191) at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1160) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) at java.lang.Thread.run(Thread.java:812)"MemoryMXBean notification dispatcher" java.lang.Thread.State: RUNNABLE at com.ibm.lang.management.internal.MemoryNotificationThread.processNotificationLoop(Native Method) at com.ibm.lang.management.internal.MemoryNotificationThread.run(MemoryNotificationThread.java:183) {code} > Forked VM terminated without properly saying goodbye with AciveMQ > ----------------------------------------------------------------- > > Key: SUREFIRE-1631 > URL: https://issues.apache.org/jira/browse/SUREFIRE-1631 > Project: Maven Surefire > Issue Type: Bug > Components: Maven Surefire Plugin > Affects Versions: 2.20.1, 2.22.0, 3.0.0-M2, 3.0.0-M1 > Reporter: Aaron Digulla > Assignee: Tibor Digana > Priority: Major > Attachments: shurefire-shutdownhook-bug-0.0.1.zip > > > I'm seeing spurious "The forked VM terminated without properly saying > goodbye. VM crash or System.exit called?" messages when running unit tests in > a big multi-module project. > OS: Windows 10, running Maven 3.5.0 to 3.6.0 and different versions of > Surefire (2.20.1 to 3.0.0.-M2), Java 8u171 to 8u191. > I'm running Maven from the command line using MinTTY (Cygwin). > Things I tried which have no effect: > * Reboot / Cold boot (happens first thing on Monday morning when I come into > the office and turn on my PC). > * More free memory (happens when I only have a single window open). I have > 16GB of RAM. > * Different terminal. I tried CMD prompt and urxvt (Cygwin/X). > * Different versions of the Surefire plugin or Maven > * Different JDK 8 builds > Things that affect the bug: > * Redirecting Maven's stdout to a file: mvn ... | tee mvn.log > * Redirecting all log output to a file using logback-test.xml > * Running Surefire with forkCount=0 > * Running a subset of the tests (-Dtest=...) > * Pending Windows updates (I think, not sure about this one). > Counts: I've never seen it with forkCount=0 (~ 20 test builds). I've never > seen it with redirecting log output (~ 10 builds). Redirecting sometimes > helps but not always. > One thing which I notice is that one of the tests creates an ActiveMQ broker > and uses a shutdown hook to stop it. So I created a small test project which > demonstrates that Surefire will sometimes cut off stdout. I think that > happens because the main process kills the child after a timeout (correct?). > So my guess would be that shutdown hooks can mess with the pipeline between > the surefire child VM and main Maven process. ActiveMQ might be worse since > it stops threads and execution pools (so the output comes slowly with a > couple of exceptions sprinkled in when one component loses connection because > another is shutting down). > But now, it gets weird. When the build succeeds, it takes about ~5 minutes to > run 1028 tests. The log is 25 MB. > When it fails, it takes ~8 minutes to run ~700-800 tests (this number varies) > and the log stops in the middle of a test but is also 25 MB. > Some of the time discrepancy is probably because writing to a file is faster > than printing on a terminal. The strange part is that the log file is about > the same size but 30% of the tests haven't run. Most tests log a lot, do I > would expect to see a difference of at least a few MB. The Maven part (which > contains escape sequences, etc). is just 60 KB. > Maybe the parent takes some part of the log output as "child terminated". > I'm running out of ideas what to try next. I think a way to log the > communication between parent and child would help. Also the parent should > terminate the child and then read stdout until EOF to we can see anything > that happens afterwards. -- This message was sent by Atlassian Jira (v8.3.4#803005)