57 elapsedTimer Phase::_t_macroExpand;
58 elapsedTimer Phase::_t_peephole;
59 elapsedTimer Phase::_t_postalloc_expand;
60 elapsedTimer Phase::_t_codeGeneration;
61 elapsedTimer Phase::_t_registerMethod;
62 elapsedTimer Phase::_t_temporaryTimer1;
63 elapsedTimer Phase::_t_temporaryTimer2;
64 elapsedTimer Phase::_t_idealLoopVerify;
65
66 // Subtimers for _t_optimizer
67 elapsedTimer Phase::_t_iterGVN;
68 elapsedTimer Phase::_t_iterGVN2;
69 elapsedTimer Phase::_t_incrInline;
70
71 // Subtimers for _t_registerAllocation
72 elapsedTimer Phase::_t_ctorChaitin;
73 elapsedTimer Phase::_t_buildIFGphysical;
74 elapsedTimer Phase::_t_computeLive;
75 elapsedTimer Phase::_t_regAllocSplit;
76 elapsedTimer Phase::_t_postAllocCopyRemoval;
77 elapsedTimer Phase::_t_fixupSpills;
78
79 // Subtimers for _t_output
80 elapsedTimer Phase::_t_instrSched;
81 elapsedTimer Phase::_t_buildOopMaps;
82 #endif
83
84 //------------------------------Phase------------------------------------------
85 Phase::Phase( PhaseNumber pnum ) : _pnum(pnum), C( pnum == Compiler ? NULL : Compile::current()) {
86 // Poll for requests from shutdown mechanism to quiesce compiler (4448539, 4448544).
87 // This is an effective place to poll, since the compiler is full of phases.
88 // In particular, every inlining site uses a recursively created Parse phase.
89 CompileBroker::maybe_block();
90 }
91
92 #ifndef PRODUCT
93 static const double minimum_reported_time = 0.0001; // seconds
94 static const double expected_method_compile_coverage = 0.97; // %
95 static const double minimum_meaningful_method_compile = 2.00; // seconds
96
119 tty->print_cr (" ccp : %3.3f sec", Phase::_t_ccp.seconds());
120 tty->print_cr (" iterGVN2 : %3.3f sec", Phase::_t_iterGVN2.seconds());
121 tty->print_cr (" macroExpand : %3.3f sec", Phase::_t_macroExpand.seconds());
122 tty->print_cr (" graphReshape : %3.3f sec", Phase::_t_graphReshaping.seconds());
123 double optimizer_subtotal = Phase::_t_iterGVN.seconds() + Phase::_t_iterGVN2.seconds() +
124 Phase::_t_escapeAnalysis.seconds() + Phase::_t_macroEliminate.seconds() +
125 Phase::_t_idealLoop.seconds() + Phase::_t_ccp.seconds() +
126 Phase::_t_macroExpand.seconds() + Phase::_t_graphReshaping.seconds();
127 double percent_of_optimizer = ((optimizer_subtotal == 0.0) ? 0.0 : (optimizer_subtotal / Phase::_t_optimizer.seconds() * 100.0));
128 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", optimizer_subtotal, percent_of_optimizer);
129 }
130 tty->print_cr (" matcher : %3.3f sec", Phase::_t_matcher.seconds());
131 tty->print_cr (" scheduler : %3.3f sec", Phase::_t_scheduler.seconds());
132 tty->print_cr (" regalloc : %3.3f sec", Phase::_t_registerAllocation.seconds());
133 if( Verbose || WizardMode ) {
134 tty->print_cr (" ctorChaitin : %3.3f sec", Phase::_t_ctorChaitin.seconds());
135 tty->print_cr (" buildIFG : %3.3f sec", Phase::_t_buildIFGphysical.seconds());
136 tty->print_cr (" computeLive : %3.3f sec", Phase::_t_computeLive.seconds());
137 tty->print_cr (" regAllocSplit : %3.3f sec", Phase::_t_regAllocSplit.seconds());
138 tty->print_cr (" postAllocCopyRemoval: %3.3f sec", Phase::_t_postAllocCopyRemoval.seconds());
139 tty->print_cr (" fixupSpills : %3.3f sec", Phase::_t_fixupSpills.seconds());
140 double regalloc_subtotal = Phase::_t_ctorChaitin.seconds() +
141 Phase::_t_buildIFGphysical.seconds() + Phase::_t_computeLive.seconds() +
142 Phase::_t_regAllocSplit.seconds() + Phase::_t_fixupSpills.seconds() +
143 Phase::_t_postAllocCopyRemoval.seconds();
144 double percent_of_regalloc = ((regalloc_subtotal == 0.0) ? 0.0 : (regalloc_subtotal / Phase::_t_registerAllocation.seconds() * 100.0));
145 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", regalloc_subtotal, percent_of_regalloc);
146 }
147 tty->print_cr (" blockOrdering : %3.3f sec", Phase::_t_blockOrdering.seconds());
148 tty->print_cr (" peephole : %3.3f sec", Phase::_t_peephole.seconds());
149 if (Matcher::require_postalloc_expand) {
150 tty->print_cr (" postalloc_expand: %3.3f sec", Phase::_t_postalloc_expand.seconds());
151 }
152 tty->print_cr (" codeGen : %3.3f sec", Phase::_t_codeGeneration.seconds());
153 tty->print_cr (" install_code : %3.3f sec", Phase::_t_registerMethod.seconds());
154 tty->print_cr (" -------------- : ----------");
155 double phase_subtotal = Phase::_t_parser.seconds() +
156 Phase::_t_optimizer.seconds() + Phase::_t_graphReshaping.seconds() +
157 Phase::_t_matcher.seconds() + Phase::_t_scheduler.seconds() +
158 Phase::_t_registerAllocation.seconds() + Phase::_t_blockOrdering.seconds() +
159 Phase::_t_codeGeneration.seconds() + Phase::_t_registerMethod.seconds();
160 double percent_of_method_compile = ((phase_subtotal == 0.0) ? 0.0 : phase_subtotal / Phase::_t_methodCompilation.seconds()) * 100.0;
161 // counters inside Compile::CodeGen include time for adapters and stubs
162 // so phase-total can be greater than 100%
163 tty->print_cr (" total : %3.3f sec, %3.2f %%", phase_subtotal, percent_of_method_compile);
|
57 elapsedTimer Phase::_t_macroExpand;
58 elapsedTimer Phase::_t_peephole;
59 elapsedTimer Phase::_t_postalloc_expand;
60 elapsedTimer Phase::_t_codeGeneration;
61 elapsedTimer Phase::_t_registerMethod;
62 elapsedTimer Phase::_t_temporaryTimer1;
63 elapsedTimer Phase::_t_temporaryTimer2;
64 elapsedTimer Phase::_t_idealLoopVerify;
65
66 // Subtimers for _t_optimizer
67 elapsedTimer Phase::_t_iterGVN;
68 elapsedTimer Phase::_t_iterGVN2;
69 elapsedTimer Phase::_t_incrInline;
70
71 // Subtimers for _t_registerAllocation
72 elapsedTimer Phase::_t_ctorChaitin;
73 elapsedTimer Phase::_t_buildIFGphysical;
74 elapsedTimer Phase::_t_computeLive;
75 elapsedTimer Phase::_t_regAllocSplit;
76 elapsedTimer Phase::_t_postAllocCopyRemoval;
77 elapsedTimer Phase::_t_mergeMultidefs;
78 elapsedTimer Phase::_t_fixupSpills;
79
80 // Subtimers for _t_output
81 elapsedTimer Phase::_t_instrSched;
82 elapsedTimer Phase::_t_buildOopMaps;
83 #endif
84
85 //------------------------------Phase------------------------------------------
86 Phase::Phase( PhaseNumber pnum ) : _pnum(pnum), C( pnum == Compiler ? NULL : Compile::current()) {
87 // Poll for requests from shutdown mechanism to quiesce compiler (4448539, 4448544).
88 // This is an effective place to poll, since the compiler is full of phases.
89 // In particular, every inlining site uses a recursively created Parse phase.
90 CompileBroker::maybe_block();
91 }
92
93 #ifndef PRODUCT
94 static const double minimum_reported_time = 0.0001; // seconds
95 static const double expected_method_compile_coverage = 0.97; // %
96 static const double minimum_meaningful_method_compile = 2.00; // seconds
97
120 tty->print_cr (" ccp : %3.3f sec", Phase::_t_ccp.seconds());
121 tty->print_cr (" iterGVN2 : %3.3f sec", Phase::_t_iterGVN2.seconds());
122 tty->print_cr (" macroExpand : %3.3f sec", Phase::_t_macroExpand.seconds());
123 tty->print_cr (" graphReshape : %3.3f sec", Phase::_t_graphReshaping.seconds());
124 double optimizer_subtotal = Phase::_t_iterGVN.seconds() + Phase::_t_iterGVN2.seconds() +
125 Phase::_t_escapeAnalysis.seconds() + Phase::_t_macroEliminate.seconds() +
126 Phase::_t_idealLoop.seconds() + Phase::_t_ccp.seconds() +
127 Phase::_t_macroExpand.seconds() + Phase::_t_graphReshaping.seconds();
128 double percent_of_optimizer = ((optimizer_subtotal == 0.0) ? 0.0 : (optimizer_subtotal / Phase::_t_optimizer.seconds() * 100.0));
129 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", optimizer_subtotal, percent_of_optimizer);
130 }
131 tty->print_cr (" matcher : %3.3f sec", Phase::_t_matcher.seconds());
132 tty->print_cr (" scheduler : %3.3f sec", Phase::_t_scheduler.seconds());
133 tty->print_cr (" regalloc : %3.3f sec", Phase::_t_registerAllocation.seconds());
134 if( Verbose || WizardMode ) {
135 tty->print_cr (" ctorChaitin : %3.3f sec", Phase::_t_ctorChaitin.seconds());
136 tty->print_cr (" buildIFG : %3.3f sec", Phase::_t_buildIFGphysical.seconds());
137 tty->print_cr (" computeLive : %3.3f sec", Phase::_t_computeLive.seconds());
138 tty->print_cr (" regAllocSplit : %3.3f sec", Phase::_t_regAllocSplit.seconds());
139 tty->print_cr (" postAllocCopyRemoval: %3.3f sec", Phase::_t_postAllocCopyRemoval.seconds());
140 tty->print_cr (" mergeMultidefs: %3.3f sec", Phase::_t_mergeMultidefs.seconds());
141 tty->print_cr (" fixupSpills : %3.3f sec", Phase::_t_fixupSpills.seconds());
142 double regalloc_subtotal = Phase::_t_ctorChaitin.seconds() +
143 Phase::_t_buildIFGphysical.seconds() + Phase::_t_computeLive.seconds() +
144 Phase::_t_regAllocSplit.seconds() + Phase::_t_fixupSpills.seconds() +
145 Phase::_t_postAllocCopyRemoval.seconds() + Phase::_t_mergeMultidefs.seconds();
146 double percent_of_regalloc = ((regalloc_subtotal == 0.0) ? 0.0 : (regalloc_subtotal / Phase::_t_registerAllocation.seconds() * 100.0));
147 tty->print_cr (" subtotal : %3.3f sec, %3.2f %%", regalloc_subtotal, percent_of_regalloc);
148 }
149 tty->print_cr (" blockOrdering : %3.3f sec", Phase::_t_blockOrdering.seconds());
150 tty->print_cr (" peephole : %3.3f sec", Phase::_t_peephole.seconds());
151 if (Matcher::require_postalloc_expand) {
152 tty->print_cr (" postalloc_expand: %3.3f sec", Phase::_t_postalloc_expand.seconds());
153 }
154 tty->print_cr (" codeGen : %3.3f sec", Phase::_t_codeGeneration.seconds());
155 tty->print_cr (" install_code : %3.3f sec", Phase::_t_registerMethod.seconds());
156 tty->print_cr (" -------------- : ----------");
157 double phase_subtotal = Phase::_t_parser.seconds() +
158 Phase::_t_optimizer.seconds() + Phase::_t_graphReshaping.seconds() +
159 Phase::_t_matcher.seconds() + Phase::_t_scheduler.seconds() +
160 Phase::_t_registerAllocation.seconds() + Phase::_t_blockOrdering.seconds() +
161 Phase::_t_codeGeneration.seconds() + Phase::_t_registerMethod.seconds();
162 double percent_of_method_compile = ((phase_subtotal == 0.0) ? 0.0 : phase_subtotal / Phase::_t_methodCompilation.seconds()) * 100.0;
163 // counters inside Compile::CodeGen include time for adapters and stubs
164 // so phase-total can be greater than 100%
165 tty->print_cr (" total : %3.3f sec, %3.2f %%", phase_subtotal, percent_of_method_compile);
|