hotspot/test/gc/g1/TestGCLogMessages.java
author tschatzl
Tue, 19 Jul 2016 10:31:41 +0200
changeset 39979 b17e445924da
parent 36851 03e2f4d0a421
child 40631 ed82623d7831
permissions -rw-r--r--
8034842: Parallelize the Free CSet phase in G1 Reviewed-by: jmasa, ehelin
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
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    30
 * @library /testlibrary
36851
03e2f4d0a421 8153737: Unsupported Module
chegar
parents: 36374
diff changeset
    31
 * @modules java.base/jdk.internal.misc
29678
dd2f3932c21e 8075586: Add @modules as needed to the open hotspot tests
ykantser
parents: 28497
diff changeset
    32
 *          java.management
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    33
 */
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    34
30604
b8d532cb6420 8067013: Rename the com.oracle.java.testlibary package
ykantser
parents: 30172
diff changeset
    35
import jdk.test.lib.ProcessTools;
b8d532cb6420 8067013: Rename the com.oracle.java.testlibary package
ykantser
parents: 30172
diff changeset
    36
import jdk.test.lib.OutputAnalyzer;
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    37
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    38
public class TestGCLogMessages {
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
    39
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    40
    private enum Level {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    41
        OFF(""),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    42
        INFO("info"),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    43
        DEBUG("debug"),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    44
        TRACE("trace");
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    45
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    46
        private String logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    47
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    48
        Level(String logName) {
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    49
            this.logName = logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    50
        }
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
        public boolean lessThan(Level other) {
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    53
            return this.compareTo(other) < 0;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    54
        }
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    55
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    56
        public String toString() {
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    57
            return logName;
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    58
        }
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    59
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    60
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    61
    private class LogMessageWithLevel {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    62
        String message;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    63
        Level level;
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
    64
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    65
        public LogMessageWithLevel(String message, Level level) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    66
            this.message = message;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    67
            this.level = level;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    68
        }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    69
    };
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
    70
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    71
    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
    72
        // Update RS
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    73
        new LogMessageWithLevel("Scan HCC", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    74
        // Ext Root Scan
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    75
        new LogMessageWithLevel("Thread Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    76
        new LogMessageWithLevel("StringTable Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    77
        new LogMessageWithLevel("Universe Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    78
        new LogMessageWithLevel("JNI Handles Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    79
        new LogMessageWithLevel("ObjectSynchronizer Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    80
        new LogMessageWithLevel("FlatProfiler Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    81
        new LogMessageWithLevel("Management Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    82
        new LogMessageWithLevel("SystemDictionary Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    83
        new LogMessageWithLevel("CLDG Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    84
        new LogMessageWithLevel("JVMTI Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    85
        new LogMessageWithLevel("SATB Filtering", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    86
        new LogMessageWithLevel("CM RefProcessor Roots", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    87
        new LogMessageWithLevel("Wait For Strong CLD", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    88
        new LogMessageWithLevel("Weak CLD Roots", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    89
        // Redirty Cards
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
    90
        new LogMessageWithLevel("Redirty Cards", Level.DEBUG),
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    91
        new LogMessageWithLevel("Parallel Redirty", Level.TRACE),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    92
        new LogMessageWithLevel("Redirtied Cards", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    93
        // Misc Top-level
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    94
        new LogMessageWithLevel("Code Roots Purge", Level.DEBUG),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    95
        new LogMessageWithLevel("String Dedup Fixup", Level.INFO),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
    96
        new LogMessageWithLevel("Expand Heap After Collection", Level.INFO),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
    97
        // Free CSet
39979
b17e445924da 8034842: Parallelize the Free CSet phase in G1
tschatzl
parents: 36851
diff changeset
    98
        new LogMessageWithLevel("Free Collection Set", Level.INFO),
b17e445924da 8034842: Parallelize the Free CSet phase in G1
tschatzl
parents: 36851
diff changeset
    99
        new LogMessageWithLevel("Free Collection Set Serial", Level.DEBUG),
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   100
        new LogMessageWithLevel("Young Free Collection Set", Level.DEBUG),
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   101
        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
   102
        // Humongous Eager Reclaim
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   103
        new LogMessageWithLevel("Humongous Reclaim", Level.DEBUG),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   104
        new LogMessageWithLevel("Humongous Register", Level.DEBUG),
36367
313c87c050f7 8076463: Add logging for the preserve CM referents task
tschatzl
parents: 35061
diff changeset
   105
        // Preserve CM Referents
313c87c050f7 8076463: Add logging for the preserve CM referents task
tschatzl
parents: 35061
diff changeset
   106
        new LogMessageWithLevel("Preserve CM Refs", Level.DEBUG),
36368
569d6e8072a9 8150630: Add logging for ParScanThreadState merge phase
tschatzl
parents: 36367
diff changeset
   107
        // Merge PSS
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   108
        new LogMessageWithLevel("Merge Per-Thread State", Level.INFO),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   109
    };
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   110
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   111
    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
   112
        for (LogMessageWithLevel l : messages) {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   113
            if (level.lessThan(l.level)) {
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   114
                output.shouldNotContain(l.message);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   115
            } else {
36374
613f27cc37b9 8150068: Log the main G1 phases at info level
brutisso
parents: 36368
diff changeset
   116
                output.shouldMatch("\\[" + l.level + ".*" + l.message);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   117
            }
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
    }
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   120
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   121
    public static void main(String[] args) throws Exception {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   122
        new TestGCLogMessages().testNormalLogs();
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   123
        new TestGCLogMessages().testWithToSpaceExhaustionLogs();
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   124
    }
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
    private void testNormalLogs() throws Exception {
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   127
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   128
        ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   129
                                                                  "-Xmx10M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   130
                                                                  GCTest.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   131
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   132
        OutputAnalyzer output = new OutputAnalyzer(pb.start());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   133
        checkMessagesAtLevel(output, allLogMessages, Level.OFF);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   134
        output.shouldHaveExitValue(0);
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   135
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   136
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   137
                                                   "-XX:+UseStringDeduplication",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   138
                                                   "-Xmx10M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   139
                                                   "-Xlog:gc+phases=debug",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   140
                                                   GCTest.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   141
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   142
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   143
        checkMessagesAtLevel(output, allLogMessages, Level.DEBUG);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   144
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   145
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   146
                                                   "-XX:+UseStringDeduplication",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   147
                                                   "-Xmx10M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   148
                                                   "-Xlog:gc+phases=trace",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   149
                                                   GCTest.class.getName());
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   150
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   151
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   152
        checkMessagesAtLevel(output, allLogMessages, Level.TRACE);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   153
        output.shouldHaveExitValue(0);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   154
    }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   155
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   156
    LogMessageWithLevel exhFailureMessages[] = new LogMessageWithLevel[] {
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   157
        new LogMessageWithLevel("Evacuation Failure", Level.DEBUG),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   158
        new LogMessageWithLevel("Recalculate Used", Level.TRACE),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   159
        new LogMessageWithLevel("Remove Self Forwards", Level.TRACE),
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   160
        new LogMessageWithLevel("Restore RemSet", Level.TRACE),
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   161
    };
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
    private void testWithToSpaceExhaustionLogs() throws Exception {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   164
        ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   165
                                                                  "-Xmx32M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   166
                                                                  "-Xmn16M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   167
                                                                  "-Xlog:gc+phases=debug",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   168
                                                                  GCTestWithToSpaceExhaustion.class.getName());
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   169
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   170
        OutputAnalyzer output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   171
        checkMessagesAtLevel(output, exhFailureMessages, Level.DEBUG);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   172
        output.shouldHaveExitValue(0);
23453
09cfb0164acf 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 23451
diff changeset
   173
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   174
        pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   175
                                                   "-Xmx32M",
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   176
                                                   "-Xmn16M",
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   177
                                                   "-Xlog:gc+phases=trace",
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   178
                                                   GCTestWithToSpaceExhaustion.class.getName());
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   179
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   180
        output = new OutputAnalyzer(pb.start());
35061
be6025ebffea 8145092: Use Unified Logging for the GC logging
brutisso
parents: 33577
diff changeset
   181
        checkMessagesAtLevel(output, exhFailureMessages, Level.TRACE);
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   182
        output.shouldHaveExitValue(0);
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   183
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   184
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   185
    static class GCTest {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   186
        private static byte[] garbage;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   187
        public static void main(String [] args) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   188
            System.out.println("Creating garbage");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   189
            // 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
   190
            for (int i = 0; i < 1024; i++) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   191
                garbage = new byte[128 * 1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   192
            }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   193
            System.out.println("Done");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   194
        }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   195
    }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   196
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   197
    static class GCTestWithToSpaceExhaustion {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   198
        private static byte[] garbage;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   199
        private static byte[] largeObject;
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   200
        public static void main(String [] args) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   201
            largeObject = new byte[16*1024*1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   202
            System.out.println("Creating garbage");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   203
            // 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
   204
            // some of them with to-space exhaustion.
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   205
            for (int i = 0; i < 1024; i++) {
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   206
                garbage = new byte[128 * 1024];
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   207
            }
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   208
            System.out.println("Done");
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   209
        }
23455
e541bff96524 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 23454
diff changeset
   210
    }
23451
ed2b8bb28fed 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
   211
}
29694
442c3305ba39 8027962: Per-phase timing measurements for strong roots processing
brutisso
parents: 28497
diff changeset
   212