annotate test/gc/g1/TestGCLogMessages.java @ 17764:595c0f60d50d

8029075: String deduplication in G1 Summary: Implementation of JEP 192, http://openjdk.java.net/jeps/192 Reviewed-by: brutisso, tschatzl, coleenp
author pliden
date Tue, 18 Mar 2014 19:07:22 +0100
parents eff02b5bd56c
children 570cb6369f17
Ignore whitespace changes - Everywhere: Within whitespace: At end of lines:
rev   line source
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
1 /*
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
2 * Copyright (c) 2014, Oracle and/or its affiliates. All rights reserved.
191174b49bec 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.
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
4 *
191174b49bec 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
191174b49bec 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
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
7 * published by the Free Software Foundation.
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
8 *
191174b49bec 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
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
10 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
191174b49bec 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
191174b49bec 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
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
13 * accompanied this code).
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
14 *
191174b49bec 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
191174b49bec 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,
191174b49bec 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.
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
18 *
191174b49bec 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
191174b49bec 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
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
21 * questions.
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
22 */
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
23
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
24 /*
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
25 * @test TestPrintGCDetails
17756
a07bea31ef35 8035398: Add card redirty time in "Other" time in G1
tschatzl
parents: 17755
diff changeset
26 * @bug 8035406 8027295 8035398
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
27 * @summary Ensure that the PrintGCDetails output for a minor GC with G1
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
28 * includes the expected necessary messages.
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
29 * @key gc
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
30 * @library /testlibrary
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
31 */
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
32
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
33 import com.oracle.java.testlibrary.ProcessTools;
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
34 import com.oracle.java.testlibrary.OutputAnalyzer;
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
35
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
36 public class TestGCLogMessages {
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
37 public static void main(String[] args) throws Exception {
17757
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
38 testNormalLogs();
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
39 testWithToSpaceExhaustionLogs();
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
40 }
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
41
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
42 private static void testNormalLogs() throws Exception {
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
43
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
44 ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
45 "-Xmx10M",
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
46 GCTest.class.getName());
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
47
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
48 OutputAnalyzer output = new OutputAnalyzer(pb.start());
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
49
17756
a07bea31ef35 8035398: Add card redirty time in "Other" time in G1
tschatzl
parents: 17755
diff changeset
50 output.shouldNotContain("[Redirty Cards");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
51 output.shouldNotContain("[Code Root Purge");
17764
595c0f60d50d 8029075: String deduplication in G1
pliden
parents: 17757
diff changeset
52 output.shouldNotContain("[String Dedup Fixup");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
53 output.shouldNotContain("[Young Free CSet");
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
54 output.shouldNotContain("[Non-Young Free CSet");
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
55 output.shouldHaveExitValue(0);
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
56
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
57 pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
17764
595c0f60d50d 8029075: String deduplication in G1
pliden
parents: 17757
diff changeset
58 "-XX:+UseStringDeduplication",
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
59 "-Xmx10M",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
60 "-XX:+PrintGCDetails",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
61 GCTest.class.getName());
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
62
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
63 output = new OutputAnalyzer(pb.start());
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
64
17756
a07bea31ef35 8035398: Add card redirty time in "Other" time in G1
tschatzl
parents: 17755
diff changeset
65 output.shouldContain("[Redirty Cards");
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
66 output.shouldContain("[Code Root Purge");
17764
595c0f60d50d 8029075: String deduplication in G1
pliden
parents: 17757
diff changeset
67 output.shouldContain("[String Dedup Fixup");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
68 output.shouldNotContain("[Young Free CSet");
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
69 output.shouldNotContain("[Non-Young Free CSet");
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
70 output.shouldHaveExitValue(0);
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
71
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
72 pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
17764
595c0f60d50d 8029075: String deduplication in G1
pliden
parents: 17757
diff changeset
73 "-XX:+UseStringDeduplication",
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
74 "-Xmx10M",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
75 "-XX:+PrintGCDetails",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
76 "-XX:+UnlockExperimentalVMOptions",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
77 "-XX:G1LogLevel=finest",
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
78 GCTest.class.getName());
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
79
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
80 output = new OutputAnalyzer(pb.start());
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
81
17756
a07bea31ef35 8035398: Add card redirty time in "Other" time in G1
tschatzl
parents: 17755
diff changeset
82 output.shouldContain("[Redirty Cards");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
83 output.shouldContain("[Code Root Purge");
17764
595c0f60d50d 8029075: String deduplication in G1
pliden
parents: 17757
diff changeset
84 output.shouldContain("[String Dedup Fixup");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
85 output.shouldContain("[Young Free CSet");
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
86 output.shouldContain("[Non-Young Free CSet");
17757
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
87
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
88 // also check evacuation failure messages once
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
89 output.shouldNotContain("[Evacuation Failure");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
90 output.shouldNotContain("[Recalculate Used");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
91 output.shouldNotContain("[Remove Self Forwards");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
92 output.shouldNotContain("[Restore RemSet");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
93 output.shouldHaveExitValue(0);
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
94 }
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
95
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
96 private static void testWithToSpaceExhaustionLogs() throws Exception {
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
97 ProcessBuilder pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
98 "-Xmx10M",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
99 "-Xmn5M",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
100 "-XX:+PrintGCDetails",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
101 GCTestWithToSpaceExhaustion.class.getName());
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
102
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
103 OutputAnalyzer output = new OutputAnalyzer(pb.start());
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
104 output.shouldContain("[Evacuation Failure");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
105 output.shouldNotContain("[Recalculate Used");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
106 output.shouldNotContain("[Remove Self Forwards");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
107 output.shouldNotContain("[Restore RemSet");
17755
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
108 output.shouldHaveExitValue(0);
96b1c2e06e25 8027295: Free CSet takes ~50% of young pause time
tschatzl
parents: 17753
diff changeset
109
17757
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
110 pb = ProcessTools.createJavaProcessBuilder("-XX:+UseG1GC",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
111 "-Xmx10M",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
112 "-Xmn5M",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
113 "-XX:+PrintGCDetails",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
114 "-XX:+UnlockExperimentalVMOptions",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
115 "-XX:G1LogLevel=finest",
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
116 GCTestWithToSpaceExhaustion.class.getName());
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
117
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
118 output = new OutputAnalyzer(pb.start());
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
119 output.shouldContain("[Evacuation Failure");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
120 output.shouldContain("[Recalculate Used");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
121 output.shouldContain("[Remove Self Forwards");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
122 output.shouldContain("[Restore RemSet");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
123 output.shouldHaveExitValue(0);
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
124 }
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
125
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
126 static class GCTest {
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
127 private static byte[] garbage;
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
128 public static void main(String [] args) {
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
129 System.out.println("Creating garbage");
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
130 // create 128MB of garbage. This should result in at least one GC
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
131 for (int i = 0; i < 1024; i++) {
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
132 garbage = new byte[128 * 1024];
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
133 }
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
134 System.out.println("Done");
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
135 }
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
136 }
17757
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
137
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
138 static class GCTestWithToSpaceExhaustion {
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
139 private static byte[] garbage;
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
140 private static byte[] largeObject;
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
141 public static void main(String [] args) {
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
142 largeObject = new byte[5*1024*1024];
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
143 System.out.println("Creating garbage");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
144 // create 128MB of garbage. This should result in at least one GC,
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
145 // some of them with to-space exhaustion.
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
146 for (int i = 0; i < 1024; i++) {
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
147 garbage = new byte[128 * 1024];
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
148 }
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
149 System.out.println("Done");
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
150 }
eff02b5bd56c 8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents: 17756
diff changeset
151 }
17753
191174b49bec 8035406: Improve data structure for Code Cache remembered sets
tschatzl
parents:
diff changeset
152 }