Uploaded image for project: 'NLPCraft'
  1. NLPCraft
  2. NLPCRAFT-76

Test failure on Windows.

    XMLWordPrintableJSON

Details

    • Bug
    • Status: Closed
    • Major
    • Resolution: Fixed
    • 0.6.2
    • 0.8.0
    • Tests
    • None

    Description

      Examples from 0.7.3:

      [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.22.1:test (integration-tests) on project nlpcraft: There are test failures.
      [ERROR]
      [ERROR] Please refer to D:\tmp\nlpcraft\073\apache-nlpcraft\nlpcraft\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:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot\bin\java -jar "C:\Users\Paul King\AppData\Local\Temp\surefire7851445059229675487\surefirebooter6212500639838130057.jar" "C:\Users\Paul King\AppData\Local\Temp\surefire7851445059229675487" 2021-01-18T18-48-51_629-jvmRun1 surefire17512347805813647919tmp surefire_0900453059839016198tmp"
      [ERROR] Process Exit Code: 0
      [ERROR] Crashed tests:
      [ERROR] org.apache.nlpcraft.server.rest.NCRestAskSpec
      [ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
      
      [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.22.1:test (integration-tests) on project nlpcraft: There are test failures.
      [ERROR]
      [ERROR] Please refer to D:\tmp\nlpcraft\073\apache-nlpcraft\nlpcraft\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:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot\bin\java -jar "C:\Users\Paul King\AppData\Local\Temp\surefire3495260889293679007\surefirebooter12040527775344534193.jar"
      "C:\Users\Paul King\AppData\Local\Temp\surefire3495260889293679007" 2021-01-18T18-54-27_237-jvmRun1 surefire6023894785853744471tmp surefire_02137409296786047525tmp"
      [ERROR] Process Exit Code: 0
      [ERROR] Crashed tests:
      [ERROR] org.apache.nlpcraft.probe.mgrs.nlp.enrichers.sort.NCEnricherSortSpec
      [ERROR] org.apache.maven.surefire.booter.SurefireBooterForkException: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
      

      Latest example with 0.7.4:

      [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.22.1:test (integration-tests) on project nlpcraft: There are test failures.
      [ERROR]
      [ERROR] Please refer to D:\tmp\nlpcraft\074\apache-nlpcraft\nlpcraft\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:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot\bin\java -jar "C:\Users\Paul King\AppData\Local\Temp\surefire5859117010280734854\surefirebooter7065006848197721739.jar" "C:\Users\Paul King\AppData\Local\Temp\surefire5859117010280734854" 2021-01-28T14-51-44_521-jvmRun1 surefire10986047377906380386tmp surefire_02218379737656475030tmp"
      [ERROR] Process Exit Code: 0
      [ERROR] Crashed tests:
      [ERROR] org.apache.nlpcraft.server.rest.NCRestAskSpec
      [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:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot\bin\java -jar "C:\Users\Paul King\AppData\Local\Temp\surefire5859117010280734854\surefirebooter7065006848197721739.jar" "C:\Users\Paul King\AppData\Local\Temp\surefire5859117010280734854" 2021-01-28T14-51-44_521-jvmRun1 surefire10986047377906380386tmp surefire_02218379737656475030tmp"
      [ERROR] Process Exit Code: 0
      [ERROR] Crashed tests:
      [ERROR] org.apache.nlpcraft.server.rest.NCRestAskSpec
      [ERROR]         at org.apache.maven.plugin.surefire.booterclient.ForkStarter.fork(ForkStarter.java:669)
      [ERROR]         at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:282)
      [ERROR]         at org.apache.maven.plugin.surefire.booterclient.ForkStarter.run(ForkStarter.java:245)
      [ERROR]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeProvider(AbstractSurefireMojo.java:1183)
      [ERROR]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.executeAfterPreconditionsChecked(AbstractSurefireMojo.java:1011)
      [ERROR]         at org.apache.maven.plugin.surefire.AbstractSurefireMojo.execute(AbstractSurefireMojo.java:857)
      [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:957)
      [ERROR]         at org.apache.maven.cli.MavenCli.doMain(MavenCli.java:289)
      [ERROR]         at org.apache.maven.cli.MavenCli.main(MavenCli.java:193)
      [ERROR]         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
      [ERROR]         at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
      [ERROR]         at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
      [ERROR]         at java.base/java.lang.reflect.Method.invoke(Method.java:566)
      [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)
      

      Server log has a few entries like below (might be totally unrelated):

      ...
      Jan-28|14:42:32|ERR| Failed to process selector key [ses=GridSelectorNioSessionImpl [worker=DirectNioClientWorker [super=AbstractNioClientWorker [idx=0, bytesRcvd=0, bytesSent=0, bytesRcvd0=0, bytesSent0=0, select=true, super=GridWorker [name=grid-nio-worker-tcp-comm-0, igniteInstanceName=nlpcraft, finished=false, heartbeatTs=1611808951507, hashCode=925522753, interrupted=false, runner=grid-nio-worker-tcp-comm-0-#31%nlpcraft%]]], writeBuf=java.nio.DirectByteBuffer[pos=0 lim=18 cap=32768], readBuf=java.nio.DirectByteBuffer[pos=0 lim=32768 cap=32768], inRecovery=null, outRecovery=null, closeSocket=true, outboundMessagesQueueSizeMetric=o.a.i.i.processors.metric.impl.LongAdderMetric@69a257d1, super=GridNioSessionImpl [locAddr=/127.0.0.1:47100, rmtAddr=/127.0.0.1:54174, createTime=1611808951317, closeTime=0, bytesSent=0, bytesRcvd=0, bytesSent0=0, bytesRcvd0=0, sndSchedTime=1611808951317, lastSndTime=1611808951317, lastRcvTime=1611808951317, readsPaused=false, filterChain=FilterChain[filters=[GridNioCodecFilter [parser=o.a.i.i.util.nio.GridDirectParser@5c2895c8, directMode=true], GridConnectionBytesVerifyFilter], accepted=true, markedForClose=false]]]
      java.io.IOException: An existing connection was forcibly closed by the remote host
      	at sun.nio.ch.SocketDispatcher.write0(Native Method) ~[?:?]
      	at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:51) ~[?:?]
      	at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:113) ~[?:?]
      	at sun.nio.ch.IOUtil.write(IOUtil.java:58) ~[?:?]
      	at sun.nio.ch.IOUtil.write(IOUtil.java:50) ~[?:?]
      	at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:466) ~[?:?]
      	at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processWrite0(GridNioServer.java:1708) ~[apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at org.apache.ignite.internal.util.nio.GridNioServer$DirectNioClientWorker.processWrite(GridNioServer.java:1386) ~[apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.processSelectedKeysOptimized(GridNioServer.java:2481) [apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.bodyInternal(GridNioServer.java:2243) [apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at org.apache.ignite.internal.util.nio.GridNioServer$AbstractNioClientWorker.body(GridNioServer.java:1880) [apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at org.apache.ignite.internal.util.worker.GridWorker.run(GridWorker.java:120) [apache-nlpcraft-incubating-0.7.4-all-deps.jar:0.7.4]
      	at java.lang.Thread.run(Thread.java:834) [?:?]
      Jan-28|14:42:32|WRN| Client disconnected abruptly due to network connection loss or because the connection was left open on application shutdown. [cls=class o.a.i.i.util.nio.GridNioException, msg=An existing connection was forcibly closed by the remote host]
      ...
      

      Dump files (might be totally unrelated):

      ...
      # Created at 2021-01-28T14:51:05.974
      System.exit() or native command error interrupted process checker.
      java.lang.IllegalStateException: Cannot use PPID 1821040 process information. Going to use NOOP events.
      	at org.apache.maven.surefire.booter.PpidChecker.checkProcessInfo(PpidChecker.java:155)
      	at org.apache.maven.surefire.booter.PpidChecker.isProcessAlive(PpidChecker.java:116)
      	at org.apache.maven.surefire.booter.ForkedBooter$2.run(ForkedBooter.java:214)
      	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
      	at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
      	at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
      	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
      	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
      	at java.base/java.lang.Thread.run(Thread.java:834)
      
      
      # Created at 2021-01-28T14:52:02.205
      Killing self fork JVM. PING timeout elapsed.
      ...
      ============8<==============
      ...
      # Created at 2021-01-28T14:51:04.278
      TestSet has not finished before stream error has appeared >> initializing exit by non-null configuration: null
      java.io.EOFException
      	at java.base/java.io.DataInputStream.readInt(DataInputStream.java:397)
      	at org.apache.maven.surefire.booter.MasterProcessCommand.decode(MasterProcessCommand.java:115)
      	at org.apache.maven.surefire.booter.CommandReader$CommandRunnable.run(CommandReader.java:391)
      	at java.base/java.lang.Thread.run(Thread.java:834)
      
      
      # Created at 2021-01-28T14:51:08.582
      Unexpected IOException with stream: Jan.-28|14:51:08|INF| NCService - (NCService.scala:147) - NCDialogFlowManager stopped.
      ...
      

      Winver:

      Version 20H2 (OS Build 19042.685)
      

      Java version:

      openjdk version "11.0.8" 2020-07-14
      OpenJDK Runtime Environment AdoptOpenJDK (build 11.0.8+10)
      OpenJDK 64-Bit Server VM AdoptOpenJDK (build 11.0.8+10, mixed mode)
      

      ENV:

      PATH=C:\devtools\apache-maven-3.6.3\bin;C:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\system32\Wbem;c:\devtools
      JAVA_HOME=C:\Progra~1\AdoptOpenJDK\jdk-11.0.8.10-hotspot
      M2_HOME=C:\devtools\apache-maven-3.6.3
      

      Attachments

        Activity

          People

            aradzinski Aaron Radzinski
            paulk Paul King
            Votes:
            0 Vote for this issue
            Watchers:
            2 Start watching this issue

            Dates

              Created:
              Updated:
              Resolved: