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

Make runtime/Monitor/GuaranteedAsyncDeflationIntervalTest.java more reliable

XMLWordPrintable

    • b20
    • x86_64, aarch64
    • os_x

        The following new test is failing in the JDK21 CI:

        runtime/Monitor/GuaranteedAsyncDeflationIntervalTest.java#allEnabled

        Here's a snippet from the log file:

        ----------System.err:(98/9947)----------
         stdout: [[5.824s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5815 ms)
        [5.824s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1123, max=1123
        [5.824s][info][monitorinflation] before handshaking: unlinked_count=1121, in_use_list stats: ceiling=11264, count=2, max=1123
        [5.824s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [5.825s][info][monitorinflation] deflated_count=1121, {unlinked,deleted}_count=1121 monitors in 0.0003808 secs
        [5.825s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [11.571s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5746 ms)
        [11.571s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1012, max=1123
        [11.571s][info][monitorinflation] before handshaking: unlinked_count=1010, in_use_list stats: ceiling=11264, count=2, max=1123
        [11.571s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [11.571s][info][monitorinflation] deflated_count=1010, {unlinked,deleted}_count=1010 monitors in 0.0003291 secs
        [11.571s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [17.314s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5743 ms)
        [17.314s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1076, max=1123
        [17.314s][info][monitorinflation] before handshaking: unlinked_count=1074, in_use_list stats: ceiling=11264, count=3, max=1123
        [17.314s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=3, max=1123
        [17.314s][info][monitorinflation] deflated_count=1074, {unlinked,deleted}_count=1074 monitors in 0.0003861 secs
        [17.314s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=3, max=1123
        [22.885s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5571 ms)
        [22.885s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=718, max=1123
        [22.885s][info][monitorinflation] before handshaking: unlinked_count=717, in_use_list stats: ceiling=11264, count=2, max=1123
        [22.885s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [22.886s][info][monitorinflation] deflated_count=717, {unlinked,deleted}_count=717 monitors in 0.0002403 secs
        [22.886s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [28.399s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5514 ms)
        [28.399s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1083, max=1123
        [28.400s][info][monitorinflation] before handshaking: unlinked_count=1081, in_use_list stats: ceiling=11264, count=2, max=1123
        [28.400s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [28.400s][info][monitorinflation] deflated_count=1081, {unlinked,deleted}_count=1081 monitors in 0.0003843 secs
        [28.400s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [34.046s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5647 ms)
        [34.046s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1046, max=1123
        [34.047s][info][monitorinflation] before handshaking: unlinked_count=1044, in_use_list stats: ceiling=11264, count=3, max=1123
        [34.047s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=3, max=1123
        [34.047s][info][monitorinflation] deflated_count=1044, {unlinked,deleted}_count=1044 monitors in 0.0004321 secs
        [34.047s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=3, max=1123
        [39.546s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5499 ms)
        [39.546s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=929, max=1123
        [39.546s][info][monitorinflation] before handshaking: unlinked_count=927, in_use_list stats: ceiling=11264, count=2, max=1123
        [39.546s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [39.547s][info][monitorinflation] deflated_count=927, {unlinked,deleted}_count=927 monitors in 0.0002978 secs
        [39.547s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [45.168s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5621 ms)
        [45.168s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=937, max=1123
        [45.168s][info][monitorinflation] before handshaking: unlinked_count=935, in_use_list stats: ceiling=11264, count=3, max=1123
        [45.168s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=3, max=1123
        [45.168s][info][monitorinflation] deflated_count=935, {unlinked,deleted}_count=935 monitors in 0.0003150 secs
        [45.168s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=3, max=1123
        [50.531s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5362 ms)
        [50.531s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=902, max=1123
        [50.531s][info][monitorinflation] before handshaking: unlinked_count=900, in_use_list stats: ceiling=11264, count=2, max=1123
        [50.531s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=2, max=1123
        [50.531s][info][monitorinflation] deflated_count=900, {unlinked,deleted}_count=900 monitors in 0.0003153 secs
        [50.531s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=2, max=1123
        [56.104s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5573 ms)
        [56.104s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1020, max=1123
        [56.105s][info][monitorinflation] before handshaking: unlinked_count=1018, in_use_list stats: ceiling=11264, count=3, max=1123
        [56.105s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=3, max=1123
        [56.105s][info][monitorinflation] deflated_count=1018, {unlinked,deleted}_count=1018 monitors in 0.0003662 secs
        [56.105s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=3, max=1123
        [61.543s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5438 ms)
        [61.543s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=174, max=1123
        [61.543s][info][monitorinflation] before handshaking: unlinked_count=173, in_use_list stats: ceiling=11264, count=1, max=1123
        [61.543s][info][monitorinflation] after handshaking: in_use_list stats: ceiling=11264, count=1, max=1123
        [61.543s][info][monitorinflation] deflated_count=173, {unlinked,deleted}_count=173 monitors in 0.0000727 secs
        [61.543s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=1, max=1123
        [67.200s][info][monitorinflation] Async deflation needed: guaranteed interval (5000 ms) is greater than time since last deflation (5657 ms)
        [67.200s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=11264, count=1, max=1123
        [67.200s][info][monitorinflation] end deflating: in_use_list stats: ceiling=11264, count=1, max=1123
        [67.326s][info][monitorinflation] Starting the final audit.
        [67.326s][info][monitorinflation] begin deflating: in_use_list stats: ceiling=10240, count=1, max=1123
        [67.326s][info][monitorinflation] end deflating: in_use_list stats: ceiling=10240, count=1, max=1123
        [67.326s][info][monitorinflation] Checking in_use_list:
        [67.326s][info][monitorinflation] count=1, max=1123
        [67.326s][info][monitorinflation] in_use_count=1 equals ck_in_use_count=1
        [67.326s][info][monitorinflation] in_use_max=1123 equals ck_in_use_max=1123
        [67.326s][info][monitorinflation] No errors found in in_use_list checks.
        [67.326s][info][monitorinflation] In-use monitor info:
        [67.326s][info][monitorinflation] (B -> is_busy, H -> has hash code, L -> lock status)
        [67.326s][info][monitorinflation] monitor BHL object object type
        [67.326s][info][monitorinflation] ================== === ================== ==================
        [67.326s][info][monitorinflation] 0x00007f8304607540 100 0x00000007ffd01670 java.lang.ref.NativeReferenceQueue$Lock (is_busy: waiters=1, contentions=0owner=0x0000000000000000, cxq=0x0000000000000000, EntryList=0x0000000000000000)
        ];
         stderr: []
         exitValue = 0

        java.lang.RuntimeException: 'Async deflation needed: monitors used are above the threshold' missing from stdout/stderr
        at jdk.test.lib.process.OutputAnalyzer.shouldContain(OutputAnalyzer.java:221)
        at GuaranteedAsyncDeflationIntervalTest.testAllEnabled(GuaranteedAsyncDeflationIntervalTest.java:180)
        at GuaranteedAsyncDeflationIntervalTest.main(GuaranteedAsyncDeflationIntervalTest.java:102)
        at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103)
        at java.base/java.lang.reflect.Method.invoke(Method.java:578)
        at com.sun.javatest.regtest.agent.MainActionHelper$AgentVMRunnable.run(MainActionHelper.java:333)
        at java.base/java.lang.Thread.run(Thread.java:1592)

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

        result: Failed. Execution failed: `main' threw exception: java.lang.RuntimeException: 'Async deflation needed: monitors used are above the threshold' missing from stdout/stderr

              shade Aleksey Shipilev
              dcubed Daniel Daugherty
              Votes:
              0 Vote for this issue
              Watchers:
              6 Start watching this issue

                Created:
                Updated:
                Resolved: