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

serviceability/sa/ClhsdbDumpheap.java timed out

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved
    • Priority: P4
    • Resolution: External
    • Affects Version/s: 18
    • Fix Version/s: 18
    • Component/s: hotspot
    • Subcomponent:
    • CPU:
      x86_64
    • OS:
      os_x_10.15

      Description

      The following test timed out in the JDK18 CI:

      serviceability/sa/ClhsdbDumpheap.java

      Here's a snippet from the log file:

      #section:main
      ----------messages:(6/223)----------
      command: main ClhsdbDumpheap
      reason: User specified action: run main/othervm/timeout=240 ClhsdbDumpheap
      Mode: othervm [/othervm specified]
      Timeout information:
      --- Timeout information end.
      elapsed time (seconds): 2636.942
      ----------configuration:(0/0)----------
      ----------System.out:(386/17784)----------
      Starting ClhsdbDumpheap test
      Command line: ['/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-18+14-715/macosx-x64-debug.jdk/jdk-18/fastdebug/bin/java' '-XX:MaxRAMPercentage=12.5' '-Djava.io.tmpdir=/System/Volumes/Data/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S109550/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f8f1d36d-9574-4240-8c5f-3c07f05af95f/runs/d8b77923-c7e4-4eef-afb6-3a0c28c74a42/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/tmp' '-Xcomp' '-XX:+CreateCoredumpOnCrash' '-ea' '-esa' '-XX:CompileThreshold=100' '-XX:+UnlockExperimentalVMOptions' '-server' '-XX:+TieredCompilation' '-XX:+DeoptimizeALot' '-cp' '/System/Volumes/Data/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S109550/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f8f1d36d-9574-4240-8c5f-3c07f05af95f/runs/d8b77923-c7e4-4eef-afb6-3a0c28c74a42/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/1/serviceability/sa/ClhsdbDumpheap.d:/System/Volumes/Data/mesos/work_dir/slaves/35546316-ed70-4084-8244-d672e7734fd1-S109550/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f8f1d36d-9574-4240-8c5f-3c07f05af95f/runs/d8b77923-c7e4-4eef-afb6-3a0c28c74a42/testoutput/test-support/jtreg_open_test_hotspot_jtreg_hotspot_serviceability/classes/1/test/lib' 'jdk.test.lib.apps.LingeredApp' 'e732ab9e-b1e4-4a09-b45c-e58bc2b9e173.lck']
      Started LingeredApp with pid 25803
      Target JDK is not signed.
      Starting clhsdb against 25803
      Adding 'sudo -E -n' to the command.
      [2021-09-08T02:23:18.741615Z] Gathering output for process 25827
      [2021-09-08T02:26:33.588348Z] Waiting for completion for process 25827
      [2021-09-08T02:26:36.500721Z] Waiting for completion finished for process 25827

      <snip>

      Target JDK is not signed.
      Starting clhsdb against 25803
      Adding 'sudo -E -n' to the command.
      [2021-09-08T02:57:49.484395Z] Gathering output for process 26733
      Timeout refired 2400 times
      [2021-09-08T03:01:00.227821Z] Waiting for completion for process 26733
      [2021-09-08T03:01:00.228534Z] Waiting for completion finished for process 26733
      Output:
      Attaching to process 25803, please wait...
      hsdb> hsdb> + verbose true
      hsdb> + dumpheap gz
      heap written to gz
      hsdb> + quit

      HprofReader.getStack() output:
      Dump file created Wed Sep 08 02:59:52 GMT 2021
      SerialNo 8
        jdk.test.lib.apps.LingeredApp.steadyState(Ljava/lang/Object;)V (LingeredApp.java:536)
        jdk.test.lib.apps.LingeredApp$1.run()V (LingeredApp.java:543)
      SerialNo 7
        java.lang.Object.wait(J)V (Object.java:(native method))
        java.lang.ref.ReferenceQueue.remove(J)Ljava/lang/ref/Reference; (ReferenceQueue.java:155)
        jdk.internal.ref.CleanerImpl.run()V (CleanerImpl.java:140)
        java.lang.Thread.run()V (Thread.java:833)
        jdk.internal.misc.InnocuousThread.run()V (InnocuousThread.java:162)
      SerialNo 6
      SerialNo 5
      SerialNo 4
        java.lang.Object.wait(J)V (Object.java:(native method))
        java.lang.ref.ReferenceQueue.remove(J)Ljava/lang/ref/Reference; (ReferenceQueue.java:155)
        java.lang.ref.ReferenceQueue.remove()Ljava/lang/ref/Reference; (ReferenceQueue.java:176)
        java.lang.ref.Finalizer$FinalizerThread.run()V (Finalizer.java:172)
      SerialNo 3
        java.lang.ref.Reference.waitForReferencePendingList()V (Reference.java:(native method))
        java.lang.ref.Reference.processPendingReferences()V (Reference.java:253)
        java.lang.ref.Reference$ReferenceHandler.run()V (Reference.java:215)
      SerialNo 2
        java.lang.Thread.sleep(J)V (Thread.java:(native method))
        jdk.test.lib.apps.LingeredApp.main([Ljava/lang/String;)V (LingeredApp.java:599)
      SerialNo 1

      Target JDK is not signed.
      Starting clhsdb against 25803
      Adding 'sudo -E -n' to the command.
      [2021-09-08T03:01:00.470349Z] Gathering output for process 26841
      ----------System.err:(0/0)----------
      ----------rerun:(43/7604)*----------

      <snip>

      result: Error. Program `/System/Volumes/Data/mesos/work_dir/jib-master/install/jdk-18+14-715/macosx-x64-debug.jdk/jdk-18/fastdebug/bin/java' timed out (timeout set to 2400000ms, elapsed time including timeout handling was 2636889ms).


      I'm not seeing any errors. It simply looks like the test run
      took a long time:

      > elapsed time (seconds): 2636.942

      matches with the reported task duration of 44m 01s.

        Attachments

          Activity

            People

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

              Dates

              Created:
              Updated:
              Resolved: