hotspot/test/gc/g1/TestGCLogMessages.java
author dfazunen
Mon, 29 Aug 2016 23:04:48 +0400
changeset 40886 98cb935dc074
parent 40631 ed82623d7831
child 42048 c1f066b53dd7
permissions -rw-r--r--
8164660: MinimalVM is not tested with GC tests Reviewed-by: jmasa, tschatzl
Ignore whitespace changes - Everywhere: Within whitespace: At end of lines:
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     1
/*
36367
313c87c050f7 8076463: Add logging for the preserve CM referents task
tschatzl
parents: 35061
diff changeset
     2
 * Copyright (c) 2014, 2016, Oracle and/or its affiliates. All rights reserved.
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     3
 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     4
 *
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     5
 * This code is free software; you can redistribute it and/or modify it
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     6
 * under the terms of the GNU General Public License version 2 only, as
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     7
 * published by the Free Software Foundation.
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     8
 *
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
     9
 * This code is distributed in the hope that it will be useful, but WITHOUT
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    10
 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    11
 * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    12
 * version 2 for more details (a copy is included in the LICENSE file that
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    13
 * accompanied this code).
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    14
 *
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    15
 * You should have received a copy of the GNU General Public License version
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    16
 * 2 along with this work; if not, write to the Free Software Foundation,
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    17
 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    18
 *
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    19
 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    20
 * or visit www.oracle.com if you need additional information or have any
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    21
 * questions.
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    22
 */
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    23
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    24
/*
24467
886598231964 8038928: gc/g1/TestGCLogMessages.java fail with "[Evacuation Failure'
jmasa
parents: 24104
diff changeset
    25
 * @test TestGCLogMessages
36368
569d6e8072a9 8150630: Add logging for ParScanThreadState merge phase
tschatzl
parents: 36367
diff changeset
    26
 * @bug 8035406 8027295 8035398 8019342 8027959 8048179 8027962 8069330 8076463 8150630
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
    27
 * @summary Ensure the output for a minor GC with G1
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    28
 * includes the expected necessary messages.
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    29
 * @key gc
40886
98cb935dc074 8164660: MinimalVM is not tested with GC tests
dfazunen
parents: 40631
diff changeset
    30
 * @requires vm.gc.G1
40631
ed82623d7831 8157957: ClassNotFoundException: jdk.test.lib.JDKToolFinder
ctornqvi
parents: 39979
diff changeset
    31
 * @library /test/lib
36851
03e2f4d0a421 8153737: Unsupported Module
chegar
parents: 36374
diff changeset
    32
 * @modules java.base/jdk.internal.misc
29678
dd2f3932c21e 8075586: Add @modules as needed to the open hotspot tests
ykantser
parents: 28497
diff changeset
    33
 *          java.management
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    34
 */
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    35
40631
ed82623d7831 8157957: ClassNotFoundException: jdk.test.lib.JDKToolFinder
ctornqvi
parents: 39979
diff changeset
    36
import jdk.test.lib.process.OutputAnalyzer;
ed82623d7831 8157957: ClassNotFoundException: jdk.test.lib.JDKToolFinder
ctornqvi
parents: 39979
diff changeset
    37
import jdk.test.lib.process.ProcessTools;
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    38
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    39
public class TestGCLogMessages {
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
    40
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    41
    private enum Level {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    42
        OFF(""),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    43
        INFO("info"),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    44
        DEBUG("debug"),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    45
        TRACE("trace");
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    46
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    47
        private String logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    48
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    49
        Level(String logName) {
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    50
            this.logName = logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    51
        }
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    52
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    53
        public boolean lessThan(Level other) {
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    54
            return this.compareTo(other) < 0;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    55
        }
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    56
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    57
        public String toString() {
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    58
            return logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    59
        }
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    60
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    61
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    62
    private class LogMessageWithLevel {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    63
        String message;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    64
        Level level;
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    65
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    66
        public LogMessageWithLevel(String message, Level level) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    67
            this.message = message;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    68
            this.level = level;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    69
        }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    70
    };
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
    71
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    72
    private LogMessageWithLevel allLogMessages[] = new LogMessageWithLevel[] {
33204
b8a3901ac5b3 8069330: Adjustment of concurrent refinement thresholds does not take hot card cache into account
tschatzl
parents: 30604
diff changeset
    73
        // Update RS
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    74
        new LogMessageWithLevel("Scan HCC", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    75
        // Ext Root Scan
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    76
        new LogMessageWithLevel("Thread Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    77
        new LogMessageWithLevel("StringTable Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    78
        new LogMessageWithLevel("Universe Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    79
        new LogMessageWithLevel("JNI Handles Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    80
        new LogMessageWithLevel("ObjectSynchronizer Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    81
        new LogMessageWithLevel("FlatProfiler Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    82
        new LogMessageWithLevel("Management Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    83
        new LogMessageWithLevel("SystemDictionary Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    84
        new LogMessageWithLevel("CLDG Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    85
        new LogMessageWithLevel("JVMTI Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    86
        new LogMessageWithLevel("SATB Filtering", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    87
        new LogMessageWithLevel("CM RefProcessor Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    88
        new LogMessageWithLevel("Wait For Strong CLD", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    89
        new LogMessageWithLevel("Weak CLD Roots", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    90
        // Redirty Cards
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
    91
        new LogMessageWithLevel("Redirty Cards", Level.DEBUG),
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    92
        new LogMessageWithLevel("Parallel Redirty", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    93
        new LogMessageWithLevel("Redirtied Cards", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    94
        // Misc Top-level
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    95
        new LogMessageWithLevel("Code Roots Purge", Level.DEBUG),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    96
        new LogMessageWithLevel("String Dedup Fixup", Level.INFO),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    97
        new LogMessageWithLevel("Expand Heap After Collection", Level.INFO),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    98
        // Free CSet
39979
b17e445924da 8034842: Parallelize the Free CSet phase in G1
tschatzl
parents: 36851
diff changeset
    99
        new LogMessageWithLevel("Free Collection Set", Level.INFO),
b17e445924da 8034842: Parallelize the Free CSet phase in G1
tschatzl
parents: 36851
diff changeset
   100
        new LogMessageWithLevel("Free Collection Set Serial", Level.DEBUG),
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   101
        new LogMessageWithLevel("Young Free Collection Set", Level.DEBUG),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   102
        new LogMessageWithLevel("Non-Young Free Collection Set", Level.DEBUG),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   103
        // Humongous Eager Reclaim
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   104
        new LogMessageWithLevel("Humongous Reclaim", Level.DEBUG),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   105
        new LogMessageWithLevel("Humongous Register", Level.DEBUG),
36367
313c87c050f7 8076463: Add logging for the preserve CM referents task
tschatzl
parents: 35061
diff changeset
   106
        // Preserve CM Referents
313c87c050f7 8076463: Add logging for the preserve CM referents task
tschatzl
parents: 35061
diff changeset
   107
        new LogMessageWithLevel("Preserve CM Refs", Level.DEBUG),
36368
569d6e8072a9 8150630: Add logging for ParScanThreadState merge phase
tschatzl
parents: 36367
diff changeset
   108
        // Merge PSS
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   109
        new LogMessageWithLevel("Merge Per-Thread State", Level.INFO),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   110
    };
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   111
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   112
    void checkMessagesAtLevel(OutputAnalyzer output, LogMessageWithLevel messages[], Level level) throws Exception {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   113
        for (LogMessageWithLevel l : messages) {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   114
            if (level.lessThan(l.level)) {
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   115
                output.shouldNotContain(l.message);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   116
            } else {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   117
                output.shouldMatch("\\[" + l.level + ".*" + l.message);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   118
            }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   119
        }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   120
    }
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   121
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   122
    public static void main(String[] args) throws Exception {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   123
        new TestGCLogMessages().testNormalLogs();
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   124
        new TestGCLogMessages().testWithToSpaceExhaustionLogs();
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   125
    }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   126
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   127
    private void testNormalLogs() throws Exception {
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   128
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   129
        ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   130
                                                                  "-Xmx10M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   131
                                                                  GCTest.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   132
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   133
        OutputAnalyzer output = new OutputAnalyzer(pb.start());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   134
        checkMessagesAtLevel(output, allLogMessages, Level.OFF);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   135
        output.shouldHaveExitValue(0);
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   136
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   137
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   138
                                                   "-XX:+UseStringDeduplication",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   139
                                                   "-Xmx10M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   140
                                                   "-Xlog:gc+phases=debug",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   141
                                                   GCTest.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   142
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   143
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   144
        checkMessagesAtLevel(output, allLogMessages, Level.DEBUG);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   145
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   146
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   147
                                                   "-XX:+UseStringDeduplication",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   148
                                                   "-Xmx10M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   149
                                                   "-Xlog:gc+phases=trace",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   150
                                                   GCTest.class.getName());
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   151
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   152
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   153
        checkMessagesAtLevel(output, allLogMessages, Level.TRACE);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   154
        output.shouldHaveExitValue(0);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   155
    }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   156
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   157
    LogMessageWithLevel exhFailureMessages[] = new LogMessageWithLevel[] {
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   158
        new LogMessageWithLevel("Evacuation Failure", Level.DEBUG),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   159
        new LogMessageWithLevel("Recalculate Used", Level.TRACE),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   160
        new LogMessageWithLevel("Remove Self Forwards", Level.TRACE),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   161
        new LogMessageWithLevel("Restore RemSet", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   162
    };
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   163
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   164
    private void testWithToSpaceExhaustionLogs() throws Exception {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   165
        ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   166
                                                                  "-Xmx32M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   167
                                                                  "-Xmn16M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   168
                                                                  "-Xlog:gc+phases=debug",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   169
                                                                  GCTestWithToSpaceExhaustion.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   170
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   171
        OutputAnalyzer output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   172
        checkMessagesAtLevel(output, exhFailureMessages, Level.DEBUG);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   173
        output.shouldHaveExitValue(0);
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   174
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   175
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   176
                                                   "-Xmx32M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   177
                                                   "-Xmn16M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   178
                                                   "-Xlog:gc+phases=trace",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   179
                                                   GCTestWithToSpaceExhaustion.class.getName());
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   180
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   181
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   182
        checkMessagesAtLevel(output, exhFailureMessages, Level.TRACE);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   183
        output.shouldHaveExitValue(0);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   184
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   185
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   186
    static class GCTest {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   187
        private static byte[] garbage;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   188
        public static void main(String [] args) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   189
            System.out.println("Creating garbage");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   190
            // create 128MB of garbage. This should result in at least one GC
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   191
            for (int i = 0; i < 1024; i++) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   192
                garbage = new byte[128 * 1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   193
            }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   194
            System.out.println("Done");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   195
        }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   196
    }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   197
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   198
    static class GCTestWithToSpaceExhaustion {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   199
        private static byte[] garbage;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   200
        private static byte[] largeObject;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   201
        public static void main(String [] args) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   202
            largeObject = new byte[16*1024*1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   203
            System.out.println("Creating garbage");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   204
            // create 128MB of garbage. This should result in at least one GC,
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   205
            // some of them with to-space exhaustion.
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   206
            for (int i = 0; i < 1024; i++) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   207
                garbage = new byte[128 * 1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   208
            }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   209
            System.out.println("Done");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   210
        }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   211
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   212
}
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   213