-
Bug
-
Resolution: Cannot Reproduce
-
P4
-
19
-
aarch64
-
linux
The following test failed in the JDK19 CI:
containers/docker/TestJcmdWithSideCar.java
Here's a snippet from the log file:
#section:driver
----------messages:(6/263)----------
command: driver TestJcmdWithSideCar
reason: User specified action: run driver TestJcmdWithSideCar
Mode: agentvm
Agent id: 7
Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc jdk.jartool/sun.tools.jar
elapsed time (seconds): 56.175
----------configuration:(18/2179)----------
Boot Layer
class path: /opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/javatest.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/jtreg.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/junit.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/hamcrest.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/testng.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/jcommander.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/guice.jar
patch: java.base /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/patches/java.base
Test Layer
add exports: java.base/jdk.internal.misc ALL-UNNAMED
jdk.jartool/sun.tools.jar ALL-UNNAMED
class path: /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/containers/docker/TestJcmdWithSideCar.d
/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+31-2191/src.full/open/test/hotspot/jtreg/containers/docker
/opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/test/lib
/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+31-2191/src.full/open/test/lib
----------rerun:(29/5988)*----------
<snip>
----------System.out:(49/3065)----------
[COMMAND]
podman ps
[2022-07-11T23:49:21.906496021Z] Gathering output for process 3353035
[ELAPSED: 188 ms]
[STDERR]
[STDOUT]
CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES
Full child process STDOUT was saved to docker-stdout-3353035.log
[2022-07-11T23:49:22.054370746Z] Waiting for completion for process 3353035
[2022-07-11T23:49:22.054487225Z] Waiting for completion finished for process 3353035
DockerfileConfig: using custom image name: jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_8.5_aarch64
DockerfileConfig: using custom image version: 1.0
[COMMAND]
podman build --no-cache --tag jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd jdk-internal-test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
[2022-07-11T23:49:22.281896214Z] Gathering output for process 3353064
[ELAPSED: 4488 ms]
[STDERR]
[STDOUT]
STEP 1/4: FROM jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_8.5_aarch64:1.0
STEP 2/4: COPY /jdk /jdk
--> 2d313317b80
STEP 3/4: ENV JAVA_HOME=/jdk
--> f40b644f465
STEP 4/4: CMD ["/bin/bash"]
COMMIT jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
--> d1656c12143
Successfully tagged localhost/jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
d1656c12143faf136e947d1111a80e3b0c744c45390c9bfb378085822cd9cc73
Full child process STDOUT was saved to docker-stdout-3353064.log
[2022-07-11T23:49:26.768664065Z] Waiting for completion for process 3353064
[2022-07-11T23:49:26.769586902Z] Waiting for completion finished for process 3353064
[main-container-process]:podman run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/containers/docker/TestJcmdWithSideCar.d:/test-classes/ --cap-add=SYS_PTRACE --name test-container-main --volume /tmp --volume /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/scratch/0/.:/workdir/ jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd /jdk/bin/java -cp /test-classes/ -XX:+UsePerfData EventGeneratorLoop 120
[COMMAND]
podman rmi --force jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
[2022-07-11T23:50:16.826343208Z] Gathering output for process 3353485
[ELAPSED: 201 ms]
[STDERR]
[STDOUT]
Untagged: localhost/jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
Deleted: d1656c12143faf136e947d1111a80e3b0c744c45390c9bfb378085822cd9cc73
Deleted: f40b644f465a354fec29df42e3729bd9f2ebef17cefc10c1b66b5fe6654273a2
Deleted: 2d313317b802398f771d4d191bb261a313d489e7f93ef48fd7d0785099732250
Full child process STDOUT was saved to docker-stdout-3353485.log
----------System.err:(11/648)----------
java.lang.RuntimeException: Timed out while waiting for main() to start
at TestJcmdWithSideCar$MainContainer.waitForMainMethodStart(TestJcmdWithSideCar.java:221)
at TestJcmdWithSideCar.main(TestJcmdWithSideCar.java:75)
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.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
at java.base/java.lang.Thread.run(Thread.java:1589)
JavaTest Message: Test threw exception: java.lang.RuntimeException
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Timed out while waiting for main() to start
containers/docker/TestJcmdWithSideCar.java
Here's a snippet from the log file:
#section:driver
----------messages:(6/263)----------
command: driver TestJcmdWithSideCar
reason: User specified action: run driver TestJcmdWithSideCar
Mode: agentvm
Agent id: 7
Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc jdk.jartool/sun.tools.jar
elapsed time (seconds): 56.175
----------configuration:(18/2179)----------
Boot Layer
class path: /opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/javatest.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/jtreg.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/junit.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/hamcrest.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/testng.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/jcommander.jar
/opt/mach5/mesos/work_dir/jib-master/install/jtreg/6.1/1/bundles/jtreg-6.1+1.zip/jtreg/lib/guice.jar
patch: java.base /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/patches/java.base
Test Layer
add exports: java.base/jdk.internal.misc ALL-UNNAMED
jdk.jartool/sun.tools.jar ALL-UNNAMED
class path: /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/containers/docker/TestJcmdWithSideCar.d
/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+31-2191/src.full/open/test/hotspot/jtreg/containers/docker
/opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/test/lib
/opt/mach5/mesos/work_dir/jib-master/install/jdk-19+31-2191/src.full/open/test/lib
----------rerun:(29/5988)*----------
<snip>
----------System.out:(49/3065)----------
[COMMAND]
podman ps
[2022-07-11T23:49:21.906496021Z] Gathering output for process 3353035
[ELAPSED: 188 ms]
[STDERR]
[STDOUT]
CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES
Full child process STDOUT was saved to docker-stdout-3353035.log
[2022-07-11T23:49:22.054370746Z] Waiting for completion for process 3353035
[2022-07-11T23:49:22.054487225Z] Waiting for completion finished for process 3353035
DockerfileConfig: using custom image name: jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_8.5_aarch64
DockerfileConfig: using custom image version: 1.0
[COMMAND]
podman build --no-cache --tag jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd jdk-internal-test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
[2022-07-11T23:49:22.281896214Z] Gathering output for process 3353064
[ELAPSED: 4488 ms]
[STDERR]
[STDOUT]
STEP 1/4: FROM jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_8.5_aarch64:1.0
STEP 2/4: COPY /jdk /jdk
--> 2d313317b80
STEP 3/4: ENV JAVA_HOME=/jdk
--> f40b644f465
STEP 4/4: CMD ["/bin/bash"]
COMMIT jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
--> d1656c12143
Successfully tagged localhost/jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
d1656c12143faf136e947d1111a80e3b0c744c45390c9bfb378085822cd9cc73
Full child process STDOUT was saved to docker-stdout-3353064.log
[2022-07-11T23:49:26.768664065Z] Waiting for completion for process 3353064
[2022-07-11T23:49:26.769586902Z] Waiting for completion finished for process 3353064
[main-container-process]:podman run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/2/containers/docker/TestJcmdWithSideCar.d:/test-classes/ --cap-add=SYS_PTRACE --name test-container-main --volume /tmp --volume /opt/mach5/mesos/work_dir/slaves/0c72054a-24ab-4dbb-944f-97f9341a1b96-S10206/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/857a5768-b893-4198-9b9f-36ae8436a29b/runs/1ce4ad33-3ee0-4b1b-9152-d56593d7ed4c/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/scratch/0/.:/workdir/ jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd /jdk/bin/java -cp /test-classes/ -XX:+UsePerfData EventGeneratorLoop 120
[COMMAND]
podman rmi --force jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
[2022-07-11T23:50:16.826343208Z] Gathering output for process 3353485
[ELAPSED: 201 ms]
[STDERR]
[STDOUT]
Untagged: localhost/jdk-internal:test-containers-docker-TestJcmdWithSideCar-jfr-jcmd
Deleted: d1656c12143faf136e947d1111a80e3b0c744c45390c9bfb378085822cd9cc73
Deleted: f40b644f465a354fec29df42e3729bd9f2ebef17cefc10c1b66b5fe6654273a2
Deleted: 2d313317b802398f771d4d191bb261a313d489e7f93ef48fd7d0785099732250
Full child process STDOUT was saved to docker-stdout-3353485.log
----------System.err:(11/648)----------
java.lang.RuntimeException: Timed out while waiting for main() to start
at TestJcmdWithSideCar$MainContainer.waitForMainMethodStart(TestJcmdWithSideCar.java:221)
at TestJcmdWithSideCar.main(TestJcmdWithSideCar.java:75)
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.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:312)
at java.base/java.lang.Thread.run(Thread.java:1589)
JavaTest Message: Test threw exception: java.lang.RuntimeException
JavaTest Message: shutting down test
result: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: Timed out while waiting for main() to start