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

TestGarbageCollectorMXBean.java fails with C1-only and -Xcomp

XMLWordPrintable

    • gc
    • b17

        Test
        gc/x/TestGarbageCollectorMXBean.java
        fails with
        -Xcomp -ea -esa -XX:CompileThreshold=100 -XX:+UnlockExperimentalVMOptions -XX:TieredStopAtLevel=1/2/3

        Log:
        [0.053s][info][gc] Using The Z Garbage Collector
        [2.666s][info][gc] GC(0) Garbage Collection (CodeCache GC Threshold) 6M(1%)->10M(2%)
        [6.306s][info][gc] GC(1) Garbage Collection (System.gc()) 12M(2%)->8M(2%)
        [11.576s][info][gc] GC(2) Garbage Collection (CodeCache GC Threshold) 12M(2%)->10M(2%)
        [1.915s] (MainThread) Starting GC 0
        [8.557s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [14.767s][info][gc] GC(3) Garbage Collection (System.gc()) 16M(3%)->12M(2%)
        [8.843s] (MainThread) Starting GC 1
        [8.848s] (Notification Thread) Id: 7
        [8.849s] (Notification Thread) Action: end of GC pause
        [8.849s] (Notification Thread) Cause: CodeCache GC Threshold
        [8.850s] (Notification Thread) StartTime: 11330
        [8.850s] (Notification Thread) EndTime: 11330
        [8.850s] (Notification Thread) Duration: 0
        [14.832s][info][gc] GC(4) Garbage Collection (System.gc()) 14M(3%)->12M(2%)
        [8.908s] (MainThread) Starting GC 2
        [14.905s][info][gc] GC(5) Garbage Collection (System.gc()) 14M(3%)->10M(2%)
        [8.982s] (MainThread) Starting GC 3
        [8.981s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [8.984s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [8.984s] (Notification Thread)
        [8.986s] (Notification Thread) ERROR: Cause
        [14.947s][info][gc] GC(6) Garbage Collection (System.gc()) 10M(2%)->12M(2%)
        [9.023s] (MainThread) Starting GC 4
        [15.000s][info][gc] GC(7) Garbage Collection (System.gc()) 12M(2%)->8M(2%)
        [9.077s] (MainThread) Waiting...
        [9.173s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.174s] (Notification Thread) Id: 8
        [9.174s] (Notification Thread) Action: end of GC pause
        [9.175s] (Notification Thread) Cause: CodeCache GC Threshold
        [9.175s] (Notification Thread) StartTime: 11365
        [9.175s] (Notification Thread) EndTime: 11365
        [9.175s] (Notification Thread) Duration: 0
        [9.202s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.202s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.202s] (Notification Thread)
        [9.203s] (Notification Thread) ERROR: Cause
        [9.204s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.204s] (Notification Thread) Id: 9
        [9.204s] (Notification Thread) Action: end of GC pause
        [9.204s] (Notification Thread) Cause: CodeCache GC Threshold
        [9.204s] (Notification Thread) StartTime: 11448
        [9.204s] (Notification Thread) EndTime: 11448
        [9.204s] (Notification Thread) Duration: 0
        [9.204s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.204s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.204s] (Notification Thread)
        [9.204s] (Notification Thread) ERROR: Cause
        [9.245s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.245s] (Notification Thread) Id: 3
        [9.245s] (Notification Thread) Action: end of GC cycle
        [9.245s] (Notification Thread) Cause: CodeCache GC Threshold
        [9.245s] (Notification Thread) StartTime: 11328
        [9.245s] (Notification Thread) EndTime: 11451
        [9.245s] (Notification Thread) Duration: 123
        [9.245s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 12582912(12288K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.246s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 10485760(10240K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.246s] (Notification Thread)
        [9.246s] (Notification Thread) ERROR: Cause
        [9.246s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.246s] (Notification Thread) Id: 10
        [9.247s] (Notification Thread) Action: end of GC pause
        [9.247s] (Notification Thread) Cause: System.gc()
        [9.247s] (Notification Thread) StartTime: 14538
        [9.247s] (Notification Thread) EndTime: 14538
        [9.247s] (Notification Thread) Duration: 0
        [9.247s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.247s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.247s] (Notification Thread)
        [9.247s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.247s] (Notification Thread) Id: 11
        [9.248s] (Notification Thread) Action: end of GC pause
        [9.248s] (Notification Thread) Cause: System.gc()
        [9.248s] (Notification Thread) StartTime: 14552
        [9.248s] (Notification Thread) EndTime: 14552
        [9.248s] (Notification Thread) Duration: 0
        [9.248s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.248s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.248s] (Notification Thread)
        [9.248s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.248s] (Notification Thread) Id: 12
        [9.249s] (Notification Thread) Action: end of GC pause
        [9.249s] (Notification Thread) Cause: System.gc()
        [9.249s] (Notification Thread) StartTime: 14640
        [9.249s] (Notification Thread) EndTime: 14640
        [9.249s] (Notification Thread) Duration: 0
        [9.249s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.249s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.249s] (Notification Thread)
        [9.249s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.249s] (Notification Thread) Id: 4
        [9.249s] (Notification Thread) Action: end of GC cycle
        [9.249s] (Notification Thread) Cause: System.gc()
        [9.249s] (Notification Thread) StartTime: 14537
        [9.249s] (Notification Thread) EndTime: 14643
        [9.249s] (Notification Thread) Duration: 106
        [9.249s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 16777216(16384K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.250s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 12582912(12288K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.250s] (Notification Thread)
        [9.250s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.250s] (Notification Thread) Id: 13
        [9.250s] (Notification Thread) Action: end of GC pause
        [9.250s] (Notification Thread) Cause: System.gc()
        [9.250s] (Notification Thread) StartTime: 14644
        [9.250s] (Notification Thread) EndTime: 14644
        [9.250s] (Notification Thread) Duration: 0
        [9.250s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.250s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.250s] (Notification Thread)
        [9.251s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.252s] (Notification Thread) Id: 14
        [9.252s] (Notification Thread) Action: end of GC pause
        [9.252s] (Notification Thread) Cause: System.gc()
        [9.252s] (Notification Thread) StartTime: 14662
        [9.252s] (Notification Thread) EndTime: 14662
        [9.252s] (Notification Thread) Duration: 0
        [9.252s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.252s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.252s] (Notification Thread)
        [9.252s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.252s] (Notification Thread) Id: 15
        [9.252s] (Notification Thread) Action: end of GC pause
        [9.252s] (Notification Thread) Cause: System.gc()
        [9.252s] (Notification Thread) StartTime: 14705
        [9.252s] (Notification Thread) EndTime: 14705
        [9.252s] (Notification Thread) Duration: 0
        [9.253s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.253s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.253s] (Notification Thread)
        [9.253s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.253s] (Notification Thread) Id: 5
        [9.253s] (Notification Thread) Action: end of GC cycle
        [9.253s] (Notification Thread) Cause: System.gc()
        [9.254s] (Notification Thread) StartTime: 14644
        [9.254s] (Notification Thread) EndTime: 14707
        [9.254s] (Notification Thread) Duration: 63
        [9.254s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 14680064(14336K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.254s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 12582912(12288K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.254s] (Notification Thread)
        [9.255s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.255s] (Notification Thread) Id: 16
        [9.255s] (Notification Thread) Action: end of GC pause
        [9.255s] (Notification Thread) Cause: System.gc()
        [9.255s] (Notification Thread) StartTime: 14709
        [9.255s] (Notification Thread) EndTime: 14709
        [9.255s] (Notification Thread) Duration: 0
        [9.255s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.255s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.255s] (Notification Thread)
        [9.256s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.256s] (Notification Thread) Id: 17
        [9.256s] (Notification Thread) Action: end of GC pause
        [9.256s] (Notification Thread) Cause: System.gc()
        [9.256s] (Notification Thread) StartTime: 14726
        [9.256s] (Notification Thread) EndTime: 14727
        [9.256s] (Notification Thread) Duration: 1
        [9.256s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.256s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.256s] (Notification Thread)
        [9.256s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.256s] (Notification Thread) Id: 18
        [9.256s] (Notification Thread) Action: end of GC pause
        [9.257s] (Notification Thread) Cause: System.gc()
        [9.257s] (Notification Thread) StartTime: 14779
        [9.257s] (Notification Thread) EndTime: 14779
        [9.257s] (Notification Thread) Duration: 0
        [9.257s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.257s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.257s] (Notification Thread)
        [9.276s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.276s] (Notification Thread) Id: 6
        [9.276s] (Notification Thread) Action: end of GC cycle
        [9.276s] (Notification Thread) Cause: System.gc()
        [9.276s] (Notification Thread) StartTime: 14709
        [9.276s] (Notification Thread) EndTime: 14781
        [9.276s] (Notification Thread) Duration: 72
        [9.276s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 14680064(14336K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.276s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 10485760(10240K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.276s] (Notification Thread)
        [9.278s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.278s] (Notification Thread) Id: 19
        [9.278s] (Notification Thread) Action: end of GC pause
        [9.278s] (Notification Thread) Cause: System.gc()
        [9.278s] (Notification Thread) StartTime: 14782
        [9.278s] (Notification Thread) EndTime: 14782
        [9.278s] (Notification Thread) Duration: 0
        [9.278s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.278s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.278s] (Notification Thread)
        [9.279s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.279s] (Notification Thread) Id: 20
        [9.279s] (Notification Thread) Action: end of GC pause
        [9.279s] (Notification Thread) Cause: System.gc()
        [9.279s] (Notification Thread) StartTime: 14792
        [9.279s] (Notification Thread) EndTime: 14792
        [9.279s] (Notification Thread) Duration: 0
        [9.279s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.279s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.279s] (Notification Thread)
        [9.279s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.279s] (Notification Thread) Id: 21
        [9.280s] (Notification Thread) Action: end of GC pause
        [9.280s] (Notification Thread) Cause: System.gc()
        [9.280s] (Notification Thread) StartTime: 14820
        [9.280s] (Notification Thread) EndTime: 14820
        [9.280s] (Notification Thread) Duration: 0
        [9.280s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.280s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.280s] (Notification Thread)
        [9.280s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.280s] (Notification Thread) Id: 7
        [9.280s] (Notification Thread) Action: end of GC cycle
        [9.280s] (Notification Thread) Cause: System.gc()
        [9.280s] (Notification Thread) StartTime: 14782
        [9.280s] (Notification Thread) EndTime: 14823
        [9.280s] (Notification Thread) Duration: 41
        [9.280s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 10485760(10240K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.280s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 12582912(12288K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.280s] (Notification Thread)
        [9.281s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.281s] (Notification Thread) Id: 22
        [9.281s] (Notification Thread) Action: end of GC pause
        [9.281s] (Notification Thread) Cause: System.gc()
        [9.281s] (Notification Thread) StartTime: 14824
        [9.281s] (Notification Thread) EndTime: 14824
        [9.281s] (Notification Thread) Duration: 0
        [9.281s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.281s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.281s] (Notification Thread)
        [9.282s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.282s] (Notification Thread) Id: 23
        [9.282s] (Notification Thread) Action: end of GC pause
        [9.282s] (Notification Thread) Cause: System.gc()
        [9.282s] (Notification Thread) StartTime: 14834
        [9.282s] (Notification Thread) EndTime: 14834
        [9.282s] (Notification Thread) Duration: 0
        [9.282s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.282s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.282s] (Notification Thread)
        [9.282s] (Notification Thread) ZGC Pauses (com.sun.management.gc.notification)
        [9.283s] (Notification Thread) Id: 24
        [9.283s] (Notification Thread) Action: end of GC pause
        [9.283s] (Notification Thread) Cause: System.gc()
        [9.283s] (Notification Thread) StartTime: 14874
        [9.283s] (Notification Thread) EndTime: 14874
        [9.283s] (Notification Thread) Duration: 0
        [9.283s] (Notification Thread) MemoryUsageBeforeGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.283s] (Notification Thread) MemoryUsageAfterGC: init = 0(0K) used = 0(0K) committed = 0(0K) max = 0(0K)
        [9.283s] (Notification Thread)
        [9.283s] (Notification Thread) ZGC Cycles (com.sun.management.gc.notification)
        [9.283s] (Notification Thread) Id: 8
        [9.283s] (Notification Thread) Action: end of GC cycle
        [9.283s] (Notification Thread) Cause: System.gc()
        [9.283s] (Notification Thread) StartTime: 14824
        [9.283s] (Notification Thread) EndTime: 14876
        [9.283s] (Notification Thread) Duration: 52
        [9.283s] (Notification Thread) MemoryUsageBeforeGC: init = 268435456(262144K) used = 12582912(12288K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.283s] (Notification Thread) MemoryUsageAfterGC: init = 268435456(262144K) used = 8388608(8192K) committed = 268435456(262144K) max = 536870912(524288K)
        [9.283s] (Notification Thread)
        [10.110s] (MainThread) All events received!
        [10.110s] (MainThread) minCycles: 5
        [10.111s] (MainThread) minPauses: 15
        [10.112s] (MainThread) actualCycles: 6
        [10.113s] (MainThread) actualPauses: 18
        [10.113s] (MainThread) actualErrors: 4
        ----------System.err:(11/616)----------
        java.lang.Exception: Unexpected errors
        at gc.x.TestGarbageCollectorMXBean.main(TestGarbageCollectorMXBean.java:217)
        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.MainWrapper$MainTask.run(MainWrapper.java:138)
        at java.base/java.lang.Thread.run(Thread.java:1575)

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

        STATUS:Failed.`main' threw exception: java.lang.Exception: Unexpected errors

              stefank Stefan Karlsson
              lmesnik Leonid Mesnik
              Votes:
              0 Vote for this issue
              Watchers:
              6 Start watching this issue

                Created:
                Updated:
                Resolved: