Uploaded image for project: 'JDK'
  1. JDK
  2. JDK-8308187

jdi/EventSet/resume/resume008 failed with "EventHandler> Unexpected event: ThreadStartEvent in thread resume008-thread0"

XMLWordPrintable

    • b24
    • aarch64
    • linux

      The following test failed in the JDK21 CI:

      vmTestbase/nsk/jdi/EventSet/resume/resume008/TestDescription.java

      Here's a snippet from the log file:

      #section:main
      ----------messages:(8/1157)----------
      command: main nsk.jdi.EventSet.resume.resume008 -verbose -arch=linux-aarch64 -waittime=5 -debugee.vmkind=java -transport.address=dynamic -debugee.vmkeys="-XX:MaxRAMPercentage=6.25 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/20/36/bundles/linux-aarch64/jdk-20_linux-aarch64_bin.tar.gz/jdk-20 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/741e9afd-8c02-45c3-b2e2-9db1450d0832-S108105/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a35a34da-4546-4075-bbe4-1fbd3aba8053/runs/a840585c-f648-465a-a467-55e3d3caec21/testoutput/test-support/jtreg_open_test_hotspot_jtreg_vmTestbase_nsk_jdi/tmp -Duse.JTREG_TEST_THREAD_FACTORY=Virtual -XX:+UseZGC -XX:+ZGenerational -XX:-VerifyContinuations"
      reason: User specified action: run main/othervm nsk.jdi.EventSet.resume.resume008 -verbose -arch=${os.family}-${os.simpleArch} -waittime=5 -debugee.vmkind=java -transport.address=dynamic -debugee.vmkeys="${test.vm.opts} ${test.java.opts}"
      started: Tue May 16 07:46:35 UTC 2023
      Mode: othervm [/othervm specified]
      Timeout information:
      --- Timeout information end.
      finished: Tue May 16 07:55:51 UTC 2023
      elapsed time (seconds): 555.324
      ----------configuration:(0/0)----------
      ----------System.out:(116/8607)*----------
      binder> VirtualMachineManager: version 21.0
      binder> Finding connector: default
      binder> LaunchingConnector:
      binder> name: com.sun.jdi.CommandLineLaunch
      binder> description: Launches target using Sun Java VM command line and attaches to it
      binder> transport: com.sun.tools.jdi.SunCommandLineLauncher$2@250e0413
      binder> Connector arguments:
      binder> main=nsk.share.MainWrapper Virtual nsk.jdi.EventSet.resume.resume008a \u0000-verbose\u0000 \u0000-arch=linux-aarch64\u0000 \u0000-waittime=5\u0000 \u0000-debugee.vmkind=java\u0000 \u0000-transport.address=dynamic\u0000 \u0000-debugee.vmkeys="-XX:MaxRAMPercentage=6.25 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/20/36/bundles/linux-aarch64/jdk-20_linux-aarch64_bin.tar.gz/jdk-20 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/741e9afd-8c02-45c3-b2e2-9db1450d0832-S108105/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a35a34da-4546-4075-bbe4-1fbd3aba8053/runs/a840585c-f648-465a-a467-55e3d3caec21/testoutput/test-support/jtreg_open_test_hotspot_jtreg_vmTestbase_nsk_jdi/tmp -Duse.JTREG_TEST_THREAD_FACTORY=Virtual -XX:+UseZGC -XX:+ZGenerational -XX:-VerifyContinuations"\u0000 \u0000-pipe.port=41627\u0000
      binder> includevirtualthreads=y
      binder> quote=\u0000
      binder> home=/opt/mach5/mesos/work_dir/jib-master/install/jdk-21+23-1950/linux-aarch64-debug.jdk/jdk-21/fastdebug
      binder> vmexec=java
      binder> suspend=true
      binder> options=-XX:MaxRAMPercentage=6.25 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/20/36/bundles/linux-aarch64/jdk-20_linux-aarch64_bin.tar.gz/jdk-20 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/741e9afd-8c02-45c3-b2e2-9db1450d0832-S108105/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/a35a34da-4546-4075-bbe4-1fbd3aba8053/runs/a840585c-f648-465a-a467-55e3d3caec21/testoutput/test-support/jtreg_open_test_hotspot_jtreg_vmTestbase_nsk_jdi/tmp -Duse.JTREG_TEST_THREAD_FACTORY=Virtual -XX:+UseZGC -XX:+ZGenerational -XX:-VerifyContinuations -Djdk.virtualThreadScheduler.parallelism=15
      binder> Launching debugee
      binder> Waiting for VM initialized
      Initial VMStartEvent received: VMStartEvent in thread main
      EventHandler> Adding listener nsk.share.jdi.EventHandler$2@6f0d83fe
      EventHandler> Adding listener nsk.share.jdi.EventHandler$3@7a9567e9
      EventHandler> Adding listener nsk.share.jdi.EventHandler$4@159f03a3
      EventHandler> Adding listener nsk.share.jdi.EventHandler$5@f07cc68
      EventHandler> Adding listener nsk.share.jdi.EventHandler$6@704c81a
      EventHandler> Adding listener nsk.share.jdi.EventHandler$1@a8292df
      EventHandler> waitForRequestedEventCommon: enabling remove of listener nsk.share.jdi.EventHandler$7@56c4697
      EventHandler> Adding listener nsk.share.jdi.EventHandler$7@56c4697
      EventHandler> Adding listener nsk.share.jdi.EventHandler$1@4ec73d65
      EventHandler> Adding listener nsk.share.jdi.EventHandler$8@244e750a
      EventHandler> waitForRequestedEventCommon: vm.resume called
      EventHandler> Received event set with policy = SUSPEND_EVENT_THREAD
      EventHandler> waitForRequestedEventCommon: Received event set: event set, policy:1, count:1 = {ClassPrepareEvent in thread main}
      EventHandler> Event: ClassPrepareEventImpl req class prepare request (enabled)
      EventHandler> waitForRequestedEventCommon: Received event(ClassPrepareEvent in thread main) for request(class prepare request (enabled))
      EventHandler> Removing listener nsk.share.jdi.EventHandler$7@56c4697
      EventHandler> Removing listener nsk.share.jdi.EventHandler$1@4ec73d65
      EventHandler> Removing listener nsk.share.jdi.EventHandler$8@244e750a
      debugger> Received ClassPrepareEvent for debuggee class: nsk.jdi.EventSet.resume.resume008a
      binder> Breakpoint set:
      breakpoint request nsk.jdi.EventSet.resume.resume008a:77 (disabled)
      EventHandler> Adding listener nsk.share.jdi.TestDebuggerType1$1@17cf5f50
      debugger> TESTING BEGINS
      debugger> RESUME DEBUGGEE VM
      debugger> shouldRunAfterBreakpoint: entered
      debugger> shouldRunAfterBreakpoint: waiting for breakpoint event during 1 sec.
      debugee.stderr> **> debuggee: debuggee started!
      EventHandler> Received event set with policy = SUSPEND_ALL
      EventHandler> Event: BreakpointEventImpl req breakpoint request nsk.jdi.EventSet.resume.resume008a:77 (enabled)
      debugger> Received communication breakpoint event.
      debugger> shouldRunAfterBreakpoint: received breakpoint event.
      debugger> shouldRunAfterBreakpoint: exited with true.
      debugger> :::::: case: # 0
      debugger> ......waiting for new ThreadStartEvent : 0
      EventHandler> waitForRequestedEventCommon: enabling remove of listener nsk.share.jdi.EventHandler$7@50953e07
      EventHandler> Adding listener nsk.share.jdi.EventHandler$7@50953e07
      EventHandler> Adding listener nsk.share.jdi.EventHandler$1@ad783f2
      EventHandler> Adding listener nsk.share.jdi.EventHandler$8@4afc6ae0
      EventHandler> waitForRequestedEventCommon: vm.resume called
      EventHandler> Received event set with policy = SUSPEND_NONE
      EventHandler> waitForRequestedEventCommon: Received event set: event set, policy:0, count:1 = {ThreadStartEvent in thread Cleaner-1}
      EventHandler> Event: ThreadStartEventImpl req thread start request (enabled)
      debugee.stderr> **> debuggee: 'run': enter :: threadName == resume008-thread0
      debugee.stderr> **> debuggee: 'run': exit :: threadName == resume008-thread0
      EventHandler> waitForRequestedEventCommon: Received event(ThreadStartEvent in thread Cleaner-1) for request(thread start request (enabled))
      EventHandler> Removing listener nsk.share.jdi.EventHandler$7@50953e07
      EventHandler> Received event set with policy = SUSPEND_NONE
      EventHandler> waitForRequestedEventCommon: Received event set: event set, policy:0, count:1 = {ThreadStartEvent in thread resume008-thread0}
      EventHandler> Event: ThreadStartEventImpl req thread start request (enabled)
      # ERROR: EventHandler> Unexpected event: ThreadStartEvent in thread resume008-thread0
      The following stacktrace is for failure analysis.
      nsk.share.TestFailure: EventHandler> Unexpected event: ThreadStartEvent in thread resume008-thread0
      at nsk.share.Log.logExceptionForFailureAnalysis(Log.java:431)
      at nsk.share.Log.complain(Log.java:402)
      at nsk.share.jdi.EventHandler.complain(EventHandler.java:121)
      at nsk.share.jdi.EventHandler$2.eventReceived(EventHandler.java:300)
      at nsk.share.jdi.EventHandler.run(EventHandler.java:176)
      at java.base/java.lang.Thread.run(Thread.java:1583)
      debugee.stderr> **> debuggee: waitForTestCase: 0
      EventHandler> Removing listener nsk.share.jdi.EventHandler$1@ad783f2
      EventHandler> Removing listener nsk.share.jdi.EventHandler$8@4afc6ae0
      debugger> got new event: ThreadStartEvent in thread Cleaner-1
      # ERROR: debugger> ERROR: ThreadStartEvent is not for expected thread: Cleaner-1
      The following stacktrace is for failure analysis.
      nsk.share.TestFailure: debugger> ERROR: ThreadStartEvent is not for expected thread: Cleaner-1
      at nsk.share.Log.logExceptionForFailureAnalysis(Log.java:431)
      at nsk.share.Log.complain(Log.java:402)
      at nsk.share.jdi.TestDebuggerType1.complain(TestDebuggerType1.java:78)
      at nsk.share.jdi.TestDebuggerType1.setFailedStatus(TestDebuggerType1.java:85)
      at nsk.jdi.EventSet.resume.resume008.testRun(resume008.java:146)
      at nsk.share.jdi.TestDebuggerType1.runThis(TestDebuggerType1.java:119)
      at nsk.jdi.EventSet.resume.resume008.run(resume008.java:84)
      at nsk.jdi.EventSet.resume.resume008.main(resume008.java:79)
      at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
      at java.base/java.lang.reflect.Method.invoke(Method.java:578)
      at com.sun.javatest.regtest.agent.MainWrapper$MainTask.run(MainWrapper.java:138)
      at java.base/java.lang.VirtualThread.run(VirtualThread.java:311)
      debugger> TESTING ENDS
      debugger> Waiting for debuggee's exit...
      EventHandler> waitForVMDisconnect
      Timeout refired 480 times
      EventHandler> Received event set with policy = SUSPEND_NONE
      EventHandler> Event: VMDisconnectEventImpl req null
      EventHandler> receieved VMDisconnect
      EventHandler> Removing listener nsk.share.jdi.EventHandler$5@f07cc68
      EventHandler> finished
      EventHandler> waitForVMDisconnect: done
      # ERROR: debugger> Event handler returned unexpected exit status: 2
      # ERROR: debugger> Debuggee returned unexpected exit status: 134


      #>
      #> SUMMARY: Following errors occured
      #> during test execution:
      #>
      # ERROR: EventHandler> Unexpected event: ThreadStartEvent in thread resume008-thread0
      # ERROR: debugger> ERROR: ThreadStartEvent is not for expected thread: Cleaner-1
      # ERROR: debugger> Event handler returned unexpected exit status: 2
      # ERROR: debugger> Debuggee returned unexpected exit status: 134
      ----------System.err:(0/0)----------
      ----------rerun:(41/9023)*----------


      This test failure reports a couple of ERRORs:

      ERROR: EventHandler> Unexpected event: ThreadStartEvent in thread resume008-thread0

      ERROR: debugger> ERROR: ThreadStartEvent is not for expected thread: Cleaner-1

            cjplummer Chris Plummer
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            4 Start watching this issue

              Created:
              Updated:
              Resolved: