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

gc/TestFullGCCount.java fails with "System.gc collections miscounted."

    XMLWordPrintable

Details

    • gc
    • b29
    • x86_64
    • linux

    Backports

      Description

        The following test failed in the JDK20 CI:

        gc/TestFullGCCount.java

        Here's a snippet from the log file:

        #section:main
        ----------messages:(7/325)----------
        command: main -Xlog:gc gc.TestFullGCCount
        reason: User specified action: run main/othervm -Xlog:gc gc.TestFullGCCount
        started: Wed Dec 07 12:33:02 UTC 2022
        Mode: othervm [/othervm specified]
        Additional options from @modules: --add-modules java.management
        finished: Wed Dec 07 12:33:03 UTC 2022
        elapsed time (seconds): 1.117
        ----------configuration:(3/43)----------
        Boot Layer
          add modules: java.management

        ----------System.out:(104/6390)----------
        [0.032s][info][gc] Using G1
        [0.603s][info][gc] GC(0) Pause Young (Concurrent Start) (System.gc()) 3M->1M(372M) 78.863ms
        [0.603s][info][gc] GC(1) Concurrent Mark Cycle
        [0.629s][info][gc] GC(1) Pause Remark 2M->2M(14M) 1.880ms
        [0.630s][info][gc] GC(1) Pause Cleanup 2M->2M(14M) 0.039ms
        [0.630s][info][gc] GC(1) Concurrent Mark Cycle 26.648ms
        [0.637s][info][gc] GC(2) Pause Young (Concurrent Start) (System.gc()) 2M->1M(14M) 2.838ms
        [0.637s][info][gc] GC(3) Concurrent Mark Cycle
        [0.642s][info][gc] GC(3) Pause Remark 1M->1M(10M) 1.592ms
        [0.643s][info][gc] GC(3) Pause Cleanup 1M->1M(10M) 0.034ms
        [0.643s][info][gc] GC(3) Concurrent Mark Cycle 6.077ms
        [0.646s][info][gc] GC(4) Pause Young (Concurrent Start) (System.gc()) 1M->1M(10M) 3.533ms
        [0.646s][info][gc] GC(5) Concurrent Mark Cycle
        [0.658s][info][gc] GC(5) Pause Remark 1M->1M(10M) 3.093ms
        [0.660s][info][gc] GC(5) Pause Cleanup 1M->1M(10M) 0.072ms
        [0.660s][info][gc] GC(5) Concurrent Mark Cycle 13.624ms
        [0.662s][info][gc] GC(6) Pause Young (Concurrent Start) (System.gc()) 1M->1M(10M) 1.342ms
        [0.662s][info][gc] GC(7) Concurrent Mark Cycle
        [0.669s][info][gc] GC(7) Pause Remark 1M->1M(10M) 2.324ms
        [0.672s][info][gc] GC(7) Pause Cleanup 1M->1M(10M) 0.087ms
        [0.672s][info][gc] GC(7) Concurrent Mark Cycle 9.233ms
        [0.674s][info][gc] GC(8) Pause Young (Concurrent Start) (System.gc()) 1M->1M(190M) 1.999ms
        [0.674s][info][gc] GC(9) Concurrent Mark Cycle
        [0.682s][info][gc] GC(9) Pause Remark 1M->1M(14M) 2.208ms
        [0.684s][info][gc] GC(9) Pause Cleanup 1M->1M(14M) 0.069ms
        [0.684s][info][gc] GC(9) Concurrent Mark Cycle 10.534ms
        [0.693s][info][gc] GC(10) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 8.582ms
        [0.693s][info][gc] GC(11) Concurrent Mark Cycle
        [0.714s][info][gc] GC(11) Pause Remark 1M->1M(14M) 9.284ms
        [0.716s][info][gc] GC(11) Pause Cleanup 1M->1M(14M) 0.105ms
        [0.716s][info][gc] GC(11) Concurrent Mark Cycle 23.064ms
        [0.719s][info][gc] GC(12) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 2.824ms
        [0.719s][info][gc] GC(13) Concurrent Mark Cycle
        [0.727s][info][gc] GC(13) Pause Remark 1M->1M(14M) 2.196ms
        [0.739s][info][gc] GC(13) Pause Cleanup 1M->1M(14M) 0.090ms
        [0.739s][info][gc] GC(13) Concurrent Mark Cycle 20.239ms
        [0.741s][info][gc] GC(14) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.231ms
        [0.741s][info][gc] GC(15) Concurrent Mark Cycle
        [0.762s][info][gc] GC(15) Pause Remark 1M->1M(14M) 2.136ms
        [0.776s][info][gc] GC(15) Pause Cleanup 1M->1M(14M) 0.087ms
        [0.776s][info][gc] GC(15) Concurrent Mark Cycle 35.388ms
        [0.778s][info][gc] GC(16) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.360ms
        [0.778s][info][gc] GC(17) Concurrent Mark Cycle
        [0.784s][info][gc] GC(17) Pause Remark 1M->1M(14M) 2.048ms
        [0.788s][info][gc] GC(17) Pause Cleanup 1M->1M(14M) 0.088ms
        [0.790s][info][gc] GC(17) Concurrent Mark Cycle 12.094ms
        [0.792s][info][gc] GC(18) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.495ms
        [0.792s][info][gc] GC(19) Concurrent Mark Cycle
        [0.803s][info][gc] GC(19) Pause Remark 1M->1M(14M) 1.875ms
        [0.806s][info][gc] GC(19) Pause Cleanup 1M->1M(14M) 0.074ms
        [0.806s][info][gc] GC(19) Concurrent Mark Cycle 14.634ms
        [0.808s][info][gc] GC(20) Pause Young (Concurrent Start) (System.gc()) 1M->1M(192M) 1.825ms
        [0.808s][info][gc] GC(21) Concurrent Mark Cycle
        [0.820s][info][gc] GC(21) Pause Remark 1M->1M(14M) 4.792ms
        [0.824s][info][gc] GC(21) Pause Cleanup 1M->1M(14M) 1.035ms
        [0.824s][info][gc] GC(21) Concurrent Mark Cycle 15.157ms
        [0.826s][info][gc] GC(22) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 2.122ms
        [0.826s][info][gc] GC(23) Concurrent Mark Cycle
        [0.845s][info][gc] GC(23) Pause Remark 1M->1M(14M) 1.462ms
        [0.847s][info][gc] GC(23) Pause Cleanup 1M->1M(14M) 0.072ms
        [0.847s][info][gc] GC(23) Concurrent Mark Cycle 20.912ms
        [0.849s][info][gc] GC(24) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.353ms
        [0.849s][info][gc] GC(25) Concurrent Mark Cycle
        [0.860s][info][gc] GC(25) Pause Remark 1M->1M(14M) 3.916ms
        [0.865s][info][gc] GC(25) Pause Cleanup 1M->1M(14M) 0.083ms
        [0.865s][info][gc] GC(25) Concurrent Mark Cycle 16.646ms
        [0.867s][info][gc] GC(26) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.359ms
        [0.867s][info][gc] GC(27) Concurrent Mark Cycle
        [0.887s][info][gc] GC(27) Pause Remark 1M->1M(14M) 1.954ms
        [0.889s][info][gc] GC(27) Pause Cleanup 1M->1M(14M) 0.071ms
        [0.889s][info][gc] GC(27) Concurrent Mark Cycle 21.804ms
        [0.891s][info][gc] GC(28) Pause Young (Concurrent Start) (System.gc()) 1M->1M(192M) 2.433ms
        [0.891s][info][gc] GC(29) Concurrent Mark Cycle
        [0.897s][info][gc] GC(29) Pause Remark 1M->1M(14M) 1.866ms
        [0.899s][info][gc] GC(29) Pause Cleanup 1M->1M(14M) 0.075ms
        [0.899s][info][gc] GC(29) Concurrent Mark Cycle 8.231ms
        [0.901s][info][gc] GC(30) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 0.935ms
        [0.901s][info][gc] GC(31) Concurrent Mark Cycle
        [0.920s][info][gc] GC(31) Pause Remark 1M->1M(14M) 16.175ms
        [0.926s][info][gc] GC(31) Pause Cleanup 1M->1M(14M) 0.090ms
        [0.927s][info][gc] GC(31) Concurrent Mark Cycle 26.918ms
        [0.929s][info][gc] GC(32) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.123ms
        [0.929s][info][gc] GC(33) Concurrent Mark Cycle
        [0.950s][info][gc] GC(33) Pause Remark 1M->1M(14M) 2.920ms
        [0.953s][info][gc] GC(33) Pause Cleanup 1M->1M(14M) 0.079ms
        [0.953s][info][gc] GC(33) Concurrent Mark Cycle 23.972ms
        [0.954s][info][gc] GC(34) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 1.282ms
        [0.954s][info][gc] GC(35) Concurrent Mark Cycle
        [0.960s][info][gc] GC(35) Pause Remark 1M->1M(14M) 1.618ms
        [0.961s][info][gc] GC(35) Pause Cleanup 1M->1M(14M) 0.096ms
        [0.961s][info][gc] GC(35) Concurrent Mark Cycle 7.062ms
        [0.963s][info][gc] GC(36) Pause Young (Concurrent Start) (System.gc()) 1M->1M(192M) 1.511ms
        [0.963s][info][gc] GC(37) Concurrent Mark Cycle
        [0.967s][info][gc] GC(37) Pause Remark 1M->1M(14M) 1.354ms
        [0.969s][info][gc] GC(37) Pause Cleanup 1M->1M(14M) 0.086ms
        [0.969s][info][gc] GC(37) Concurrent Mark Cycle 6.450ms
        [0.976s][info][gc] GC(38) Pause Young (Concurrent Start) (System.gc()) 1M->1M(14M) 6.280ms
        [0.976s][info][gc] GC(39) Concurrent Mark Cycle
        [0.996s][info][gc] GC(39) Pause Remark 1M->1M(14M) 2.758ms
        [0.998s][info][gc] GC(39) Pause Cleanup 1M->1M(14M) 0.303ms
        [0.999s][info][gc] GC(39) Concurrent Mark Cycle 23.000ms
        Checking: G1 Young Generation
        Checking: G1 Old Generation
        Checking: G1 Concurrent GC
        ----------System.err:(31/1836)----------
        Collector 'G1 Concurrent GC' has increment 2 at iteration 0
        Collector 'G1 Concurrent GC' has increment 2 at iteration 1
        Collector 'G1 Concurrent GC' has increment 2 at iteration 2
        Collector 'G1 Concurrent GC' has increment 2 at iteration 3
        Collector 'G1 Concurrent GC' has increment 2 at iteration 4
        Collector 'G1 Concurrent GC' has increment 2 at iteration 5
        Collector 'G1 Concurrent GC' has increment 2 at iteration 6
        Collector 'G1 Concurrent GC' has increment 2 at iteration 7
        Collector 'G1 Concurrent GC' has increment 2 at iteration 8
        Collector 'G1 Concurrent GC' has increment 2 at iteration 9
        Collector 'G1 Concurrent GC' has increment 2 at iteration 10
        Collector 'G1 Concurrent GC' has increment 2 at iteration 11
        Collector 'G1 Concurrent GC' has increment 2 at iteration 12
        Collector 'G1 Concurrent GC' has increment 2 at iteration 13
        Collector 'G1 Concurrent GC' has increment 2 at iteration 14
        Collector 'G1 Concurrent GC' has increment 2 at iteration 15
        Collector 'G1 Concurrent GC' has increment 2 at iteration 16
        Collector 'G1 Concurrent GC' has increment 2 at iteration 17
        Collector 'G1 Concurrent GC' has increment 2 at iteration 18

        java.lang.RuntimeException: FAILED: System.gc collections miscounted.
        at gc.TestFullGCCount.main(TestFullGCCount.java:89)
        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.MainWrapper$MainThread.run(MainWrapper.java:125)
        at java.base/java.lang.Thread.run(Thread.java:1599)

        JavaTest Message: Test threw exception: java.lang.RuntimeException: FAILED: System.gc collections miscounted.
        JavaTest Message: shutting down test

        STATUS:Failed.`main' threw exception: java.lang.RuntimeException: FAILED: System.gc collections miscounted.
        ----------rerun:(40/5993)*----------

        Attachments

          Issue Links

            Activity

              People

                kbarrett Kim Barrett
                dcubed Daniel Daugherty
                Votes:
                0 Vote for this issue
                Watchers:
                5 Start watching this issue

                Dates

                  Created:
                  Updated:
                  Resolved: