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

jdk/jfr/jvm/TestDumpOnCrash.java timed out

XMLWordPrintable

    • jfr
    • b19
    • x86_64, aarch64
    • linux, windows

        The following test timed out in the JDK17 CI:

        jdk/jfr/jvm/TestDumpOnCrash.java

        Here's a snippet from the log file:

        #section:main
        ----------messages:(7/488)----------
        command: main --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED jdk.jfr.jvm.TestDumpOnCrash
        reason: User specified action: run main/othervm --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED jdk.jfr.jvm.TestDumpOnCrash
        Mode: othervm [/othervm specified]
        Additional options from @modules: --add-modules java.base,java.management,jdk.jfr --add-exports java.base/jdk.internal.misc=ALL-UNNAMED
        Timeout information:
        --- Timeout information end.
        elapsed time (seconds): 1234.787
        ----------configuration:(4/121)----------
        Boot Layer
          add modules: java.base java.management jdk.jfr
          add exports: java.base/jdk.internal.misc ALL-UNNAMED

        ----------System.out:(79/7724)*----------
        Test case for crasher jdk.jfr.jvm.TestDumpOnCrash$CrasherIllegalAccess
        Command line: [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java.exe -cp T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\jdk\\jfr\\jvm\\TestDumpOnCrash.d;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\jdk\\jdk\\jfr\\jvm;T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar -Xmx512m -XX:MaxRAMPercentage=4 -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\tmp -ea -esa -XX:NativeMemoryTracking=detail -Xmx64m -XX:-CreateCoredumpOnCrash --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED -XX:StartFlightRecording=dumponexit=true,disk=true jdk.jfr.jvm.TestDumpOnCrash$CrasherIllegalAccess ]
        [2021-03-03T08:40:00.022982900Z] Gathering output for process 44028
        ========== Crasher process output:
        Started recording 1. No limit specified, using maxsize=250MB as default.

        Use jcmd 44028 JFR.dump name=1 filename=FILEPATH to copy recording data to file.
        #
        # A fatal error has been detected by the Java Runtime Environment:
        #
        # EXCEPTION_ACCESS_VIOLATION (0xc0000005) at pc=0x00007ffcc167dafb, pid=44028, tid=20624
        #
        # JRE version: Java(TM) SE Runtime Environment (17.0+12) (fastdebug build 17-ea+12-LTS-905)
        # Java VM: Java HotSpot(TM) 64-Bit Server VM (fastdebug 17-ea+12-LTS-905, mixed mode, sharing, tiered, compressed oops, compressed class ptrs, g1 gc, windows-amd64)
        # Problematic frame:
        # V [jvm.dll+0xc8dafb] Unsafe_PutInt+0x17b
        #
        # CreateCoredumpOnCrash turned off, no core file dumped
        #
        # JFR recording file will be written. Location: T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\scratch\\0\\hs_err_pid44028.jfr
        #
        # An error report file with more information is saved as:
        # T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\scratch\\0\\hs_err_pid44028.log
        #
        # If you would like to submit a bug report, please visit:
        # https://bugreport.java.com/bugreport/crash.jsp
        #

        ==================================
        File size=165681
        Found event jdk.NativeMethodSample
        Test case for crasher jdk.jfr.jvm.TestDumpOnCrash$CrasherIllegalAccess
        Command line: [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java.exe -cp T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\jdk\\jfr\\jvm\\TestDumpOnCrash.d;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\jdk\\jdk\\jfr\\jvm;T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar -Xmx512m -XX:MaxRAMPercentage=4 -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\tmp -ea -esa -XX:NativeMemoryTracking=detail -Xmx64m -XX:-CreateCoredumpOnCrash --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED -XX:StartFlightRecording=dumponexit=true,disk=false jdk.jfr.jvm.TestDumpOnCrash$CrasherIllegalAccess ]
        [2021-03-03T08:40:03.983964600Z] Gathering output for process 55592
        ========== Crasher process output:
        Started recording 1.

        Use jcmd 55592 JFR.dump name=1 filename=FILEPATH to copy recording data to file.
        #
        # A fatal error has been detected by the Java Runtime Environment:
        #
        # EXCEPTION_ACCESS_VIOLATION (0xc0000005) at pc=0x00007ffcc167dafb, pid=55592, tid=21636
        #
        # JRE version: Java(TM) SE Runtime Environment (17.0+12) (fastdebug build 17-ea+12-LTS-905)
        # Java VM: Java HotSpot(TM) 64-Bit Server VM (fastdebug 17-ea+12-LTS-905, mixed mode, sharing, tiered, compressed oops, compressed class ptrs, g1 gc, windows-amd64)
        # Problematic frame:
        # V [jvm.dll+0xc8dafb] Unsafe_PutInt+0x17b
        #
        # CreateCoredumpOnCrash turned off, no core file dumped
        #
        # JFR recording file will be written. Location: T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\scratch\\0\\hs_err_pid55592.jfr
        #
        # An error report file with more information is saved as:
        # T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\scratch\\0\\hs_err_pid55592.log
        #
        # If you would like to submit a bug report, please visit:
        # https://bugreport.java.com/bugreport/crash.jsp
        #

        ==================================
        File size=165697
        Found event jdk.JavaMonitorWait
        Test case for crasher jdk.jfr.jvm.TestDumpOnCrash$CrasherHalt
        Command line: [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java.exe -cp T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\jdk\\jfr\\jvm\\TestDumpOnCrash.d;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\jdk\\jdk\\jfr\\jvm;T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar -Xmx512m -XX:MaxRAMPercentage=4 -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\tmp -ea -esa -XX:NativeMemoryTracking=detail -Xmx64m -XX:-CreateCoredumpOnCrash --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED -XX:StartFlightRecording=dumponexit=true,disk=true jdk.jfr.jvm.TestDumpOnCrash$CrasherHalt ]
        [2021-03-03T08:40:07.122968500Z] Gathering output for process 67224
        ========== Crasher process output:
        Started recording 1. No limit specified, using maxsize=250MB as default.

        Use jcmd 67224 JFR.dump name=1 filename=FILEPATH to copy recording data to file.
        Running Runtime.getRuntime.halt

        ==================================
        File size=165109
        Found event jdk.ThreadStart
        Test case for crasher jdk.jfr.jvm.TestDumpOnCrash$CrasherHalt
        Command line: [c:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\windows-x64-debug.jdk\\jdk-17\\fastdebug\\bin\\java.exe -cp T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\jdk\\jfr\\jvm\\TestDumpOnCrash.d;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\jdk\\jdk\\jfr\\jvm;T:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\classes\\1\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jdk-17+12-905\\src.full\\open\\test\\lib;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\javatest.jar;C:\\ade\\mesos\\work_dir\\jib-master\\install\\jtreg\\5.1\\b01\\bundles\\jtreg_bin-5.1.zip\\jtreg\\lib\\jtreg.jar -Xmx512m -XX:MaxRAMPercentage=4 -Djava.io.tmpdir=t:\\testoutput\\test-support\\jtreg_open_test_jdk_jdk_svc\\tmp -ea -esa -XX:NativeMemoryTracking=detail -Xmx64m -XX:-CreateCoredumpOnCrash --add-exports=java.base/jdk.internal.misc=ALL-UNNAMED -XX:StartFlightRecording=dumponexit=true,disk=false jdk.jfr.jvm.TestDumpOnCrash$CrasherHalt ]
        [2021-03-03T08:40:11.932979500Z] Gathering output for process 4344
        ========== Crasher process output:
        Timeout refired 1200 times
        ----------System.err:(0/0)----------
        ----------rerun:(46/5511)*----------

        <snip>

        result: Error. Program `c:\ade\mesos\work_dir\jib-master\install\jdk-17+12-905\windows-x64-debug.jdk\jdk-17\fastdebug\bin\java' timed out (timeout set to 1200000ms, elapsed time including timeout handling was 1234769ms).


        The crashes shown in the test log are intentionally induced
        as part of the test.

        Here's the JVM args for the test task:

        -XX:NativeMemoryTracking=detail

        It looks like the test limits the Java heap via '-Xmx512m'
        and '-Xmx64m' so this should not be a case of the test
        taking too long to generate a dump due to a large Java heap.

              egahlin Erik Gahlin
              dcubed Daniel Daugherty
              Votes:
              0 Vote for this issue
              Watchers:
              4 Start watching this issue

                Created:
                Updated:
                Resolved: