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

containers/docker/TestJFREvents.java failed with Exception: Found free size value is bad

XMLWordPrintable

    • x86_64
    • linux

      The following test failed in the JDK23 CI:

      containers/docker/TestJFREvents.java

      Here's a snippet from the log file:

      #section:driver
      ----------messages:(8/329)----------
      command: driver TestJFREvents
      reason: User specified action: run driver TestJFREvents
      started: Sat Mar 09 00:48:15 UTC 2024
      Mode: agentvm
      Agent id: 15
      Additional exports to unnamed modules from @modules: java.base/jdk.internal.misc jdk.jartool/sun.tools.jar
      finished: Sat Mar 09 00:48:54 UTC 2024
      elapsed time (seconds): 38.629
      ----------configuration:(16/2003)----------

      <snip>

      ----------System.out:(164/11542)----------
      Test Environment: detected availableCPUs = 12
      [COMMAND]
      docker ps
      [2024-03-09T00:48:16.953046546Z] Gathering output for process 23536
      [ELAPSED: 167 ms]
      [STDERR]

      [STDOUT]
      CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES

      Full child process STDOUT was saved to docker-stdout-23536.log
      [2024-03-09T00:48:17.043976658Z] Waiting for completion for process 23536
      [2024-03-09T00:48:17.044205300Z] Waiting for completion finished for process 23536
      DockerfileConfig: using custom image name: jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9
      DockerfileConfig: using custom image version: 1.0
      [COMMAND]
      docker build --no-cache --tag jdk-internal:test-containers-docker-TestJFREvents-jfr-events jdk-internal-test-containers-docker-TestJFREvents-jfr-events
      [2024-03-09T00:48:17.286432347Z] Gathering output for process 23553
      [ELAPSED: 24767 ms]
      [STDERR]

      [STDOUT]
      Sending build context to Docker daemon 376.9MB

      Step 1/4 : FROM jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9:1.0
       ---> 2481d1748ac6
      Step 2/4 : COPY /jdk /jdk
       ---> fefd15611876
      Step 3/4 : ENV JAVA_HOME=/jdk
       ---> Running in 43139f94b687
      Removing intermediate container 43139f94b687
       ---> bd66beff53f4
      Step 4/4 : CMD ["/bin/bash"]
       ---> Running in 8c3164de30ce
      Removing intermediate container 8c3164de30ce
       ---> ee70bd2beca3
      Successfully built ee70bd2beca3
      Successfully tagged jdk-internal:test-containers-docker-TestJFREvents-jfr-events

      Full child process STDOUT was saved to docker-stdout-23553.log
      [2024-03-09T00:48:42.053793390Z] Waiting for completion for process 23553
      [2024-03-09T00:48:42.053985022Z] Waiting for completion finished for process 23553
      ========== NEW TEST CASE: Memory: --memory = 200m
      [COMMAND]
      docker run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/3/containers/docker/TestJFREvents.d:/test-classes/ --memory=200m jdk-internal:test-containers-docker-TestJFREvents-jfr-events /jdk/bin/java -XX:+ErrorFileToStderr -cp /test-classes/ -XX:MaxRAMPercentage=4.16667 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/21/35/bundles/linux-x64/jdk-21_linux-x64_bin.tar.gz/jdk-21 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/tmp -Djdk.test.docker.image.name=jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9 -Djdk.test.docker.image.version=1.0 -Djtreg.log.vmprops=true JfrReporter jdk.PhysicalMemory
      [2024-03-09T00:48:42.064753433Z] Gathering output for process 23649
      [ELAPSED: 2522 ms]
      [STDERR]

      [STDOUT]
      WARNING: java.io.tmpdir directory does not exist
      ===== EventType: jdk.PhysicalMemory
      startTime = 1310207230
      totalSize = 209715200
      usedSize = 70934528
      ===== EventType: jdk.PhysicalMemory
      startTime = 1311825550
      totalSize = 209715200
      usedSize = 70918144

      Full child process STDOUT was saved to docker-stdout-23649.log
      [2024-03-09T00:48:44.586094454Z] Waiting for completion for process 23649
      [2024-03-09T00:48:44.586264166Z] Waiting for completion finished for process 23649
      ========== NEW TEST CASE: Memory: --memory = 500m
      [COMMAND]
      docker run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/3/containers/docker/TestJFREvents.d:/test-classes/ --memory=500m jdk-internal:test-containers-docker-TestJFREvents-jfr-events /jdk/bin/java -XX:+ErrorFileToStderr -cp /test-classes/ -XX:MaxRAMPercentage=4.16667 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/21/35/bundles/linux-x64/jdk-21_linux-x64_bin.tar.gz/jdk-21 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/tmp -Djdk.test.docker.image.name=jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9 -Djdk.test.docker.image.version=1.0 -Djtreg.log.vmprops=true JfrReporter jdk.PhysicalMemory
      [2024-03-09T00:48:44.588010397Z] Gathering output for process 23784
      [ELAPSED: 2473 ms]
      [STDERR]

      [STDOUT]
      WARNING: java.io.tmpdir directory does not exist
      ===== EventType: jdk.PhysicalMemory
      startTime = 1264886287
      totalSize = 524288000
      usedSize = 81186816
      ===== EventType: jdk.PhysicalMemory
      startTime = 1266542990
      totalSize = 524288000
      usedSize = 81293312

      Full child process STDOUT was saved to docker-stdout-23784.log
      [2024-03-09T00:48:47.061302077Z] Waiting for completion for process 23784
      [2024-03-09T00:48:47.061517524Z] Waiting for completion finished for process 23784
      ========== NEW TEST CASE: Memory: --memory = 1g
      [COMMAND]
      docker run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/3/containers/docker/TestJFREvents.d:/test-classes/ --memory=1g jdk-internal:test-containers-docker-TestJFREvents-jfr-events /jdk/bin/java -XX:+ErrorFileToStderr -cp /test-classes/ -XX:MaxRAMPercentage=4.16667 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/21/35/bundles/linux-x64/jdk-21_linux-x64_bin.tar.gz/jdk-21 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/tmp -Djdk.test.docker.image.name=jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9 -Djdk.test.docker.image.version=1.0 -Djtreg.log.vmprops=true JfrReporter jdk.PhysicalMemory
      [2024-03-09T00:48:47.063070981Z] Gathering output for process 23913
      [ELAPSED: 2404 ms]
      [STDERR]

      [STDOUT]
      WARNING: java.io.tmpdir directory does not exist
      ===== EventType: jdk.PhysicalMemory
      startTime = 1198982996
      totalSize = 1073741824
      usedSize = 87609344
      ===== EventType: jdk.PhysicalMemory
      startTime = 1200611164
      totalSize = 1073741824
      usedSize = 87597056

      Full child process STDOUT was saved to docker-stdout-23913.log
      [2024-03-09T00:48:49.467533819Z] Waiting for completion for process 23913
      [2024-03-09T00:48:49.467742512Z] Waiting for completion finished for process 23913
      ========== NEW TEST CASE: Memory: --memory = 200m --memory-swap = 200m
      [COMMAND]
      docker run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/3/containers/docker/TestJFREvents.d:/test-classes/ --memory=200m --memory-swap=200m jdk-internal:test-containers-docker-TestJFREvents-jfr-events /jdk/bin/java -XX:+ErrorFileToStderr -cp /test-classes/ -XX:MaxRAMPercentage=4.16667 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/21/35/bundles/linux-x64/jdk-21_linux-x64_bin.tar.gz/jdk-21 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/tmp -Djdk.test.docker.image.name=jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9 -Djdk.test.docker.image.version=1.0 -Djtreg.log.vmprops=true JfrReporter jdk.SwapSpace
      [2024-03-09T00:48:49.469973401Z] Gathering output for process 24045
      [ELAPSED: 2458 ms]
      [STDERR]

      [STDOUT]
      WARNING: java.io.tmpdir directory does not exist
      ===== EventType: jdk.SwapSpace
      startTime = 1254918099
      totalSize = 0
      freeSize = 0
      ===== EventType: jdk.SwapSpace
      startTime = 1256582075
      totalSize = 0
      freeSize = 0

      Full child process STDOUT was saved to docker-stdout-24045.log
      [2024-03-09T00:48:51.928948150Z] Waiting for completion for process 24045
      [2024-03-09T00:48:51.929146965Z] Waiting for completion finished for process 24045
      Found freeSize value 0 is fine
      Found freeSize value 0 is fine
      ========== NEW TEST CASE: Memory: --memory = 200m --memory-swap = 300m
      [COMMAND]
      docker run --tty=true --rm --volume /opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/classes/3/containers/docker/TestJFREvents.d:/test-classes/ --memory=200m --memory-swap=300m jdk-internal:test-containers-docker-TestJFREvents-jfr-events /jdk/bin/java -XX:+ErrorFileToStderr -cp /test-classes/ -XX:MaxRAMPercentage=4.16667 -Dtest.boot.jdk=/opt/mach5/mesos/work_dir/jib-master/install/jdk/21/35/bundles/linux-x64/jdk-21_linux-x64_bin.tar.gz/jdk-21 -Djava.io.tmpdir=/opt/mach5/mesos/work_dir/slaves/0db9c48f-6638-40d0-9a4b-bd9cc7533eb8-S9670/frameworks/1735e8a2-a1db-478c-8104-60c8b0af87dd-0196/executors/f185215d-b052-4deb-8116-88794a863c22/runs/dd2c72b1-4d4b-4d94-a27f-a121f87967ea/testoutput/test-support/jtreg_open_test_hotspot_jtreg_containers/tmp -Djdk.test.docker.image.name=jpg-data.us.oracle.com/docker-local/mach5/jdk-test/oraclelinux_7.9 -Djdk.test.docker.image.version=1.0 -Djtreg.log.vmprops=true JfrReporter jdk.SwapSpace
      [2024-03-09T00:48:51.942870394Z] Gathering output for process 24174
      [ELAPSED: 2432 ms]
      [STDERR]

      [STDOUT]
      WARNING: java.io.tmpdir directory does not exist
      ===== EventType: jdk.SwapSpace
      startTime = 1297211358
      totalSize = 104857600
      freeSize = 7065636864
      ===== EventType: jdk.SwapSpace
      startTime = 1299055544
      totalSize = 104857600
      freeSize = 104849408

      Full child process STDOUT was saved to docker-stdout-24174.log
      [2024-03-09T00:48:54.375406291Z] Waiting for completion for process 24174
      [2024-03-09T00:48:54.375572906Z] Waiting for completion finished for process 24174
      Found freeSize value 7065636864 is bad
      [COMMAND]
      docker rmi --force jdk-internal:test-containers-docker-TestJFREvents-jfr-events
      [2024-03-09T00:48:54.378100025Z] Gathering output for process 24304
      [ELAPSED: 53 ms]
      [STDERR]

      [STDOUT]
      Untagged: jdk-internal:test-containers-docker-TestJFREvents-jfr-events
      Deleted: sha256:ee70bd2beca3f449e8a19a46e8f0478a253eefef4a51c55bac0ee0472c0408ce
      Deleted: sha256:bd66beff53f4dcfb9585d0db94e35f4d690eee62ea8b7d350cd9ab36e850d6bf
      Deleted: sha256:fefd1561187614f6ce0678411d581838922870f96968edcc5a4a1c58e63d89c3
      Deleted: sha256:6f576cf22d9d88b88a3f7a499d668fe3e4c710005a7d31bf21f92e92e288e59e

      Full child process STDOUT was saved to docker-stdout-24304.log
      ----------System.err:(11/573)----------
      java.lang.Exception: Found free size value is bad
      at TestJFREvents.testSwapMemory(TestJFREvents.java:242)
      at TestJFREvents.main(TestJFREvents.java:73)
      at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
      at java.base/java.lang.reflect.Method.invoke(Method.java:580)
      at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
      at java.base/java.lang.Thread.run(Thread.java:1575)

      JavaTest Message: Test threw exception: java.lang.Exception
      JavaTest Message: shutting down test

      result: Failed. Execution failed: `main' threw exception: java.lang.Exception: Found free size value is bad

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

              Created:
              Updated: