-
Bug
-
Resolution: External
-
P4
-
18
-
x86_64
-
os_x_10.15
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.
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.