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

JCmdTestDynamicDump.java timed out in VirtualMachineImpl.read()

XMLWordPrintable

    • aarch64
    • os_x

      The following test failed in the JDK20 CI:

      runtime/cds/appcds/jcmd/JCmdTestDynamicDump.java

      Here's a snippet from the log file:

      #section:main
      ----------messages:(7/527)----------
      command: main -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
      reason: User specified action: run main/othervm/timeout=480 -Xbootclasspath/a:. -XX:+UnlockDiagnosticVMOptions -XX:+WhiteBoxAPI JCmdTestDynamicDump
      Mode: othervm [/othervm specified]
      Additional options from @modules: --add-modules jdk.jcmd --add-exports jdk.jcmd/sun.tools.common=ALL-UNNAMED --add-opens jdk.jcmd/sun.tools.common=ALL-UNNAMED
      Timeout information:
      --- Timeout information end.
      elapsed time (seconds): 2004.71
      ----------configuration:(5/160)----------
      Boot Layer
        add modules: jdk.jcmd
        add exports: jdk.jcmd/sun.tools.common ALL-UNNAMED
        add opens: jdk.jcmd/sun.tools.common ALL-UNNAMED

      ----------System.out:(22/2779)----------
      Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test
      Jar file created: /System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot

      1 Test dynamic dump with -XX:+RecordDynamicDumpInfo.

      Command line: ['/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' '-cp' '/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/test:/System/Volumes/Data/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S34753/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/7147585d-3e33-4026-878f-9db879415ed8/runs/476fc25c-21b8-4e08-bf87-ef1390477d59/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_tier2_runtime/scratch/1/boot' '-XX:+RecordDynamicDumpInfo' 'JCmdTestLingeredApp' '32004432-80bd-414a-b42a-6fd1d517f9b9.lck']
      Expected: SUCCESS
      Executing command '[/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/jcmd, 28465, VM.cds dynamic_dump mydynamic01.jsa]'
      [2022-07-20T15:21:29.260919Z] Gathering output for process 28473
      Timeout refired 1920 times
      [2022-07-20T15:53:49.445051Z] Waiting for completion for process 28473
      [2022-07-20T15:53:49.445140Z] Waiting for completion finished for process 28473
      Output and diagnostic info for process 28473 was saved into 'pid-28473-output.log'
      [2022-07-20T15:53:49.449119Z] Waiting for completion for process 28473
      [2022-07-20T15:53:49.449168Z] Waiting for completion finished for process 28473
      Command returned with exit code 134
      [2022-07-20T15:53:49.449249Z] Waiting for completion for process 28473
      [2022-07-20T15:53:49.449284Z] Waiting for completion finished for process 28473
      [2022-07-20T15:53:49.449316Z] Waiting for completion for process 28473
      [2022-07-20T15:53:49.449340Z] Waiting for completion finished for process 28473
      [2022-07-20T15:53:49.454305Z] Waiting for completion for process 28473
      [2022-07-20T15:53:49.454348Z] Waiting for completion finished for process 28473
      ----------System.err:(21/1180)----------
       stdout: [28465:
      ];
       stderr: [Java HotSpot(TM) 64-Bit Server VM warning: outputStream::do_vsnprintf output truncated -- buffer length is 2000 bytes but 2116 bytes are needed.
      ]
       exitValue = 134

      java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
      at jdk.test.lib.process.OutputAnalyzer.shouldHaveExitValue(OutputAnalyzer.java:490)
      at JCmdTestDumpBase.test(JCmdTestDumpBase.java:193)
      at JCmdTestDynamicDump.test(JCmdTestDynamicDump.java:62)
      at JCmdTestDumpBase.runTest(JCmdTestDumpBase.java:62)
      at JCmdTestDynamicDump.main(JCmdTestDynamicDump.java:147)
      at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:104)
      at java.base/java.lang.reflect.Method.invoke(Method.java:578)
      at com.sun.javatest.regtest.agent.MainWrapper$MainThread.run(MainWrapper.java:127)
      at java.base/java.lang.Thread.run(Thread.java:1589)

      JavaTest Message: Test threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
      JavaTest Message: shutting down test

      STATUS:Failed.`main' threw exception: java.lang.RuntimeException: Expected to get exit value of [0], exit value is: [134]
      ----------rerun:(38/10051)*----------

      ....
      test result: Error. Program `/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-20+7-372/macosx-aarch64-debug.jdk/jdk-20/fastdebug/bin/java' timed out (timeout set to 1920000ms, elapsed time including timeout handling was 2004708ms).

        1. bt28465.log
          77 kB
          Calvin Cheung
        2. bt28473.log
          65 kB
          Calvin Cheung

            ccheung Calvin Cheung
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            7 Start watching this issue

              Created:
              Updated: