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

containers/docker/TestJcmdWithSideCar.java failed with "RuntimeException: Timed out while waiting for main() to start"

XMLWordPrintable

    • 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

            mseledtsov Mikhailo Seledtsov
            dcubed Daniel Daugherty
            Votes:
            0 Vote for this issue
            Watchers:
            3 Start watching this issue

              Created:
              Updated:
              Resolved: