1 /*
2 * Copyright (c) 1997, 2011, Oracle and/or its affiliates. All rights reserved.
3 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
4 *
5 * This code is free software; you can redistribute it and/or modify it
6 * under the terms of the GNU General Public License version 2 only, as
7 * published by the Free Software Foundation.
8 *
9 * This code is distributed in the hope that it will be useful, but WITHOUT
10 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
11 * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License
12 * version 2 for more details (a copy is included in the LICENSE file that
13 * accompanied this code).
14 *
15 * You should have received a copy of the GNU General Public License version
16 * 2 along with this work; if not, write to the Free Software Foundation,
17 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
18 *
19 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
20 * or visit www.oracle.com if you need additional information or have any
21 * questions.
22 *
23 */
24
25 #include "precompiled.hpp"
26 #include "code/nmethod.hpp"
27 #include "compiler/compileBroker.hpp"
28 #include "opto/compile.hpp"
29 #include "opto/node.hpp"
30 #include "opto/phase.hpp"
31
32 #ifndef PRODUCT
33 int Phase::_total_bytes_compiled = 0;
34
35 elapsedTimer Phase::_t_totalCompilation;
36 elapsedTimer Phase::_t_methodCompilation;
37 elapsedTimer Phase::_t_stubCompilation;
38 #endif
39
40 // The next timers used for LogCompilation
41 elapsedTimer Phase::_t_parser;
42 elapsedTimer Phase::_t_escapeAnalysis;
43 elapsedTimer Phase::_t_optimizer;
44 elapsedTimer Phase::_t_idealLoop;
45 elapsedTimer Phase::_t_ccp;
46 elapsedTimer Phase::_t_matcher;
47 elapsedTimer Phase::_t_registerAllocation;
48 elapsedTimer Phase::_t_output;
49
50 #ifndef PRODUCT
51 elapsedTimer Phase::_t_graphReshaping;
52 elapsedTimer Phase::_t_scheduler;
53 elapsedTimer Phase::_t_blockOrdering;
54 elapsedTimer Phase::_t_macroExpand;
55 elapsedTimer Phase::_t_peephole;
56 elapsedTimer Phase::_t_codeGeneration;
57 elapsedTimer Phase::_t_registerMethod;
58 elapsedTimer Phase::_t_temporaryTimer1;
59 elapsedTimer Phase::_t_temporaryTimer2;
60 elapsedTimer Phase::_t_idealLoopVerify;
61
62 // Subtimers for _t_optimizer
63 elapsedTimer Phase::_t_iterGVN;
64 elapsedTimer Phase::_t_iterGVN2;
65
66 // Subtimers for _t_registerAllocation
67 elapsedTimer Phase::_t_ctorChaitin;
68 elapsedTimer Phase::_t_buildIFGphysical;
69 elapsedTimer Phase::_t_computeLive;
70 elapsedTimer Phase::_t_regAllocSplit;
71 elapsedTimer Phase::_t_postAllocCopyRemoval;
72 elapsedTimer Phase::_t_fixupSpills;
73
87 #ifndef PRODUCT
88 static const double minimum_reported_time = 0.0001; // seconds
89 static const double expected_method_compile_coverage = 0.97; // %
90 static const double minimum_meaningful_method_compile = 2.00; // seconds
91
92 void Phase::print_timers() {
93 tty->print_cr ("Accumulated compiler times:");
94 tty->print_cr ("---------------------------");
95 tty->print_cr (" Total compilation: %3.3f sec.", Phase::_t_totalCompilation.seconds());
96 tty->print (" method compilation : %3.3f sec", Phase::_t_methodCompilation.seconds());
97 tty->print ("/%d bytes",_total_bytes_compiled);
98 tty->print_cr (" (%3.0f bytes per sec) ", Phase::_total_bytes_compiled / Phase::_t_methodCompilation.seconds());
99 tty->print_cr (" stub compilation : %3.3f sec.", Phase::_t_stubCompilation.seconds());
100 tty->print_cr (" Phases:");
101 tty->print_cr (" parse : %3.3f sec", Phase::_t_parser.seconds());
102 tty->print_cr (" optimizer : %3.3f sec", Phase::_t_optimizer.seconds());
103 if( Verbose || WizardMode ) {
104 if (DoEscapeAnalysis) {
105 // EA is part of Optimizer.
106 tty->print_cr (" escape analysis: %3.3f sec", Phase::_t_escapeAnalysis.seconds());
107 }
108 tty->print_cr (" iterGVN : %3.3f sec", Phase::_t_iterGVN.seconds());
109 tty->print_cr (" idealLoop : %3.3f sec", Phase::_t_idealLoop.seconds());
110 tty->print_cr (" idealLoopVerify: %3.3f sec", Phase::_t_idealLoopVerify.seconds());
111 tty->print_cr (" ccp : %3.3f sec", Phase::_t_ccp.seconds());
112 tty->print_cr (" iterGVN2 : %3.3f sec", Phase::_t_iterGVN2.seconds());
113 tty->print_cr (" macroExpand : %3.3f sec", Phase::_t_macroExpand.seconds());
114 tty->print_cr (" graphReshape : %3.3f sec", Phase::_t_graphReshaping.seconds());
115 double optimizer_subtotal = Phase::_t_iterGVN.seconds() +
116 Phase::_t_idealLoop.seconds() + Phase::_t_ccp.seconds() +
117 Phase::_t_graphReshaping.seconds();
118 double percent_of_optimizer = ((optimizer_subtotal == 0.0) ? 0.0 : (optimizer_subtotal / Phase::_t_optimizer.seconds() * 100.0));
119 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", optimizer_subtotal, percent_of_optimizer);
120 }
121 tty->print_cr (" matcher : %3.3f sec", Phase::_t_matcher.seconds());
122 tty->print_cr (" scheduler : %3.3f sec", Phase::_t_scheduler.seconds());
123 tty->print_cr (" regalloc : %3.3f sec", Phase::_t_registerAllocation.seconds());
124 if( Verbose || WizardMode ) {
125 tty->print_cr (" ctorChaitin : %3.3f sec", Phase::_t_ctorChaitin.seconds());
126 tty->print_cr (" buildIFG : %3.3f sec", Phase::_t_buildIFGphysical.seconds());
127 tty->print_cr (" computeLive : %3.3f sec", Phase::_t_computeLive.seconds());
128 tty->print_cr (" regAllocSplit : %3.3f sec", Phase::_t_regAllocSplit.seconds());
129 tty->print_cr (" postAllocCopyRemoval: %3.3f sec", Phase::_t_postAllocCopyRemoval.seconds());
130 tty->print_cr (" fixupSpills : %3.3f sec", Phase::_t_fixupSpills.seconds());
131 double regalloc_subtotal = Phase::_t_ctorChaitin.seconds() +
132 Phase::_t_buildIFGphysical.seconds() + Phase::_t_computeLive.seconds() +
133 Phase::_t_regAllocSplit.seconds() + Phase::_t_fixupSpills.seconds() +
134 Phase::_t_postAllocCopyRemoval.seconds();
135 double percent_of_regalloc = ((regalloc_subtotal == 0.0) ? 0.0 : (regalloc_subtotal / Phase::_t_registerAllocation.seconds() * 100.0));
136 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", regalloc_subtotal, percent_of_regalloc);
137 }
|
1 /*
2 * Copyright (c) 1997, 2012, Oracle and/or its affiliates. All rights reserved.
3 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
4 *
5 * This code is free software; you can redistribute it and/or modify it
6 * under the terms of the GNU General Public License version 2 only, as
7 * published by the Free Software Foundation.
8 *
9 * This code is distributed in the hope that it will be useful, but WITHOUT
10 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
11 * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License
12 * version 2 for more details (a copy is included in the LICENSE file that
13 * accompanied this code).
14 *
15 * You should have received a copy of the GNU General Public License version
16 * 2 along with this work; if not, write to the Free Software Foundation,
17 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
18 *
19 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
20 * or visit www.oracle.com if you need additional information or have any
21 * questions.
22 *
23 */
24
25 #include "precompiled.hpp"
26 #include "code/nmethod.hpp"
27 #include "compiler/compileBroker.hpp"
28 #include "opto/compile.hpp"
29 #include "opto/node.hpp"
30 #include "opto/phase.hpp"
31
32 #ifndef PRODUCT
33 int Phase::_total_bytes_compiled = 0;
34
35 elapsedTimer Phase::_t_totalCompilation;
36 elapsedTimer Phase::_t_methodCompilation;
37 elapsedTimer Phase::_t_stubCompilation;
38 #endif
39
40 // The next timers used for LogCompilation
41 elapsedTimer Phase::_t_parser;
42 elapsedTimer Phase::_t_optimizer;
43 elapsedTimer Phase::_t_escapeAnalysis;
44 elapsedTimer Phase::_t_buildConnectionGraph;
45 elapsedTimer Phase::_t_idealLoop;
46 elapsedTimer Phase::_t_ccp;
47 elapsedTimer Phase::_t_matcher;
48 elapsedTimer Phase::_t_registerAllocation;
49 elapsedTimer Phase::_t_output;
50
51 #ifndef PRODUCT
52 elapsedTimer Phase::_t_graphReshaping;
53 elapsedTimer Phase::_t_scheduler;
54 elapsedTimer Phase::_t_blockOrdering;
55 elapsedTimer Phase::_t_macroEliminate;
56 elapsedTimer Phase::_t_macroExpand;
57 elapsedTimer Phase::_t_peephole;
58 elapsedTimer Phase::_t_codeGeneration;
59 elapsedTimer Phase::_t_registerMethod;
60 elapsedTimer Phase::_t_temporaryTimer1;
61 elapsedTimer Phase::_t_temporaryTimer2;
62 elapsedTimer Phase::_t_idealLoopVerify;
63
64 // Subtimers for _t_optimizer
65 elapsedTimer Phase::_t_iterGVN;
66 elapsedTimer Phase::_t_iterGVN2;
67
68 // Subtimers for _t_registerAllocation
69 elapsedTimer Phase::_t_ctorChaitin;
70 elapsedTimer Phase::_t_buildIFGphysical;
71 elapsedTimer Phase::_t_computeLive;
72 elapsedTimer Phase::_t_regAllocSplit;
73 elapsedTimer Phase::_t_postAllocCopyRemoval;
74 elapsedTimer Phase::_t_fixupSpills;
75
89 #ifndef PRODUCT
90 static const double minimum_reported_time = 0.0001; // seconds
91 static const double expected_method_compile_coverage = 0.97; // %
92 static const double minimum_meaningful_method_compile = 2.00; // seconds
93
94 void Phase::print_timers() {
95 tty->print_cr ("Accumulated compiler times:");
96 tty->print_cr ("---------------------------");
97 tty->print_cr (" Total compilation: %3.3f sec.", Phase::_t_totalCompilation.seconds());
98 tty->print (" method compilation : %3.3f sec", Phase::_t_methodCompilation.seconds());
99 tty->print ("/%d bytes",_total_bytes_compiled);
100 tty->print_cr (" (%3.0f bytes per sec) ", Phase::_total_bytes_compiled / Phase::_t_methodCompilation.seconds());
101 tty->print_cr (" stub compilation : %3.3f sec.", Phase::_t_stubCompilation.seconds());
102 tty->print_cr (" Phases:");
103 tty->print_cr (" parse : %3.3f sec", Phase::_t_parser.seconds());
104 tty->print_cr (" optimizer : %3.3f sec", Phase::_t_optimizer.seconds());
105 if( Verbose || WizardMode ) {
106 if (DoEscapeAnalysis) {
107 // EA is part of Optimizer.
108 tty->print_cr (" escape analysis: %3.3f sec", Phase::_t_escapeAnalysis.seconds());
109 tty->print_cr (" build connection graph: %3.3f sec", Phase::_t_buildConnectionGraph.seconds());
110 tty->print_cr (" macroEliminate : %3.3f sec", Phase::_t_macroEliminate.seconds());
111 }
112 tty->print_cr (" iterGVN : %3.3f sec", Phase::_t_iterGVN.seconds());
113 tty->print_cr (" idealLoop : %3.3f sec", Phase::_t_idealLoop.seconds());
114 tty->print_cr (" idealLoopVerify: %3.3f sec", Phase::_t_idealLoopVerify.seconds());
115 tty->print_cr (" ccp : %3.3f sec", Phase::_t_ccp.seconds());
116 tty->print_cr (" iterGVN2 : %3.3f sec", Phase::_t_iterGVN2.seconds());
117 tty->print_cr (" macroExpand : %3.3f sec", Phase::_t_macroExpand.seconds());
118 tty->print_cr (" graphReshape : %3.3f sec", Phase::_t_graphReshaping.seconds());
119 double optimizer_subtotal = Phase::_t_iterGVN.seconds() + Phase::_t_iterGVN2.seconds() +
120 Phase::_t_escapeAnalysis.seconds() + Phase::_t_macroEliminate.seconds() +
121 Phase::_t_idealLoop.seconds() + Phase::_t_ccp.seconds() +
122 Phase::_t_macroExpand.seconds() + Phase::_t_graphReshaping.seconds();
123 double percent_of_optimizer = ((optimizer_subtotal == 0.0) ? 0.0 : (optimizer_subtotal / Phase::_t_optimizer.seconds() * 100.0));
124 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", optimizer_subtotal, percent_of_optimizer);
125 }
126 tty->print_cr (" matcher : %3.3f sec", Phase::_t_matcher.seconds());
127 tty->print_cr (" scheduler : %3.3f sec", Phase::_t_scheduler.seconds());
128 tty->print_cr (" regalloc : %3.3f sec", Phase::_t_registerAllocation.seconds());
129 if( Verbose || WizardMode ) {
130 tty->print_cr (" ctorChaitin : %3.3f sec", Phase::_t_ctorChaitin.seconds());
131 tty->print_cr (" buildIFG : %3.3f sec", Phase::_t_buildIFGphysical.seconds());
132 tty->print_cr (" computeLive : %3.3f sec", Phase::_t_computeLive.seconds());
133 tty->print_cr (" regAllocSplit : %3.3f sec", Phase::_t_regAllocSplit.seconds());
134 tty->print_cr (" postAllocCopyRemoval: %3.3f sec", Phase::_t_postAllocCopyRemoval.seconds());
135 tty->print_cr (" fixupSpills : %3.3f sec", Phase::_t_fixupSpills.seconds());
136 double regalloc_subtotal = Phase::_t_ctorChaitin.seconds() +
137 Phase::_t_buildIFGphysical.seconds() + Phase::_t_computeLive.seconds() +
138 Phase::_t_regAllocSplit.seconds() + Phase::_t_fixupSpills.seconds() +
139 Phase::_t_postAllocCopyRemoval.seconds();
140 double percent_of_regalloc = ((regalloc_subtotal == 0.0) ? 0.0 : (regalloc_subtotal / Phase::_t_registerAllocation.seconds() * 100.0));
141 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", regalloc_subtotal, percent_of_regalloc);
142 }
|