1 /*
   2  * Copyright (c) 1997, 2013, 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/matcher.hpp"
  30 #include "opto/node.hpp"
  31 #include "opto/phase.hpp"
  32 
  33 int Phase::_total_bytes_compiled = 0;
  34 
  35 elapsedTimer Phase::_t_totalCompilation;
  36 elapsedTimer Phase::_t_methodCompilation;
  37 elapsedTimer Phase::_t_stubCompilation;
  38 
  39 // The counters to use for LogCompilation
  40 elapsedTimer Phase::timers[max_phase_timers];
  41 
  42 //------------------------------Phase------------------------------------------
  43 Phase::Phase( PhaseNumber pnum ) : _pnum(pnum), C( pnum == Compiler ? NULL : Compile::current()) {
  44   // Poll for requests from shutdown mechanism to quiesce compiler (4448539, 4448544).
  45   // This is an effective place to poll, since the compiler is full of phases.
  46   // In particular, every inlining site uses a recursively created Parse phase.
  47   CompileBroker::maybe_block();
  48 }
  49 
  50 void Phase::print_timers() {
  51   tty->print_cr ("    C2 Compile Time:     %6.3f s", Phase::_t_totalCompilation.seconds());
  52   tty->print_cr ("       Parse:              %6.3f s", timers[_t_parser].seconds());
  53   
  54   {
  55     tty->print_cr ("       Optimize:           %6.3f s", timers[_t_optimizer].seconds());
  56     if (DoEscapeAnalysis) {
  57       // EA is part of Optimizer.
  58       tty->print_cr ("         Escape Analysis:    %6.3f s", timers[_t_escapeAnalysis].seconds());
  59       tty->print_cr ("           Conn Graph:         %6.3f s", timers[_t_connectionGraph].seconds());
  60       tty->print_cr ("           Macro Eliminate:    %6.3f s", timers[_t_macroEliminate].seconds());
  61     }
  62     tty->print_cr ("         GVN 1:              %6.3f s", timers[_t_iterGVN].seconds());
  63     tty->print_cr ("         Incremental Inline: %6.3f s", timers[_t_incrInline].seconds());
  64     tty->print_cr ("         IdealLoop:          %6.3f s", timers[_t_idealLoop].seconds());
  65     tty->print_cr ("         IdealLoop Verify:   %6.3f s", timers[_t_idealLoopVerify].seconds());
  66     tty->print_cr ("         Cond Const Prop:    %6.3f s", timers[_t_ccp].seconds());
  67     tty->print_cr ("         GVN 2:              %6.3f s", timers[_t_iterGVN2].seconds());
  68     tty->print_cr ("         Macro Expand:       %6.3f s", timers[_t_macroExpand].seconds());
  69     tty->print_cr ("         Graph Reshape:      %6.3f s", timers[_t_graphReshaping].seconds());
  70     
  71     double other = timers[_t_optimizer].seconds() - 
  72       (timers[_t_escapeAnalysis].seconds() + 
  73        timers[_t_iterGVN].seconds() + 
  74        timers[_t_incrInline].seconds() + 
  75        timers[_t_idealLoop].seconds() + 
  76        timers[_t_idealLoopVerify].seconds() + 
  77        timers[_t_ccp].seconds() +    
  78        timers[_t_iterGVN2].seconds() +        
  79        timers[_t_macroExpand].seconds() +        
  80        timers[_t_graphReshaping].seconds());
  81     if (other > 0) {
  82       tty->print_cr("         Other:              %6.3f s", other);
  83     }
  84   }
  85     
  86   tty->print_cr ("       Matcher:            %6.3f s", timers[_t_matcher].seconds());
  87   tty->print_cr ("       Scheduler:          %6.3f s", timers[_t_scheduler].seconds());  
  88   
  89   {
  90     tty->print_cr ("       Regalloc:           %6.3f s", timers[_t_registerAllocation].seconds());
  91     tty->print_cr ("         Ctor Chaitin:       %6.3f s", timers[_t_ctorChaitin].seconds());
  92     tty->print_cr ("         Build IFG (virt):   %6.3f s", timers[_t_buildIFGvirtual].seconds());
  93     tty->print_cr ("         Build IFG (phys):   %6.3f s", timers[_t_buildIFGphysical].seconds());
  94     tty->print_cr ("         Compute Liveness:   %6.3f s", timers[_t_computeLive].seconds());
  95     tty->print_cr ("         Regalloc Split:     %6.3f s", timers[_t_regAllocSplit].seconds());
  96     tty->print_cr ("         Postalloc Copy Rem: %6.3f s", timers[_t_postAllocCopyRemoval].seconds());
  97     tty->print_cr ("         Fixup Spills:       %6.3f s", timers[_t_fixupSpills].seconds());
  98     tty->print_cr ("         Compact:            %6.3f s", timers[_t_chaitinCompact].seconds());
  99     tty->print_cr ("         Coalesce:           %6.3f s", timers[_t_chaitinCoalesce].seconds());
 100     tty->print_cr ("         Cache LRG:          %6.3f s", timers[_t_chaitinCacheLRG].seconds());    
 101     tty->print_cr ("         Simplify:           %6.3f s", timers[_t_chaitinSimplify].seconds());
 102     tty->print_cr ("         Select:             %6.3f s", timers[_t_chaitinSelect].seconds());
 103     
 104     double other = timers[_t_registerAllocation].seconds() - 
 105       (timers[_t_ctorChaitin].seconds() + 
 106        timers[_t_computeLive].seconds() + 
 107        timers[_t_buildIFGvirtual].seconds() + 
 108        timers[_t_buildIFGphysical].seconds() + 
 109        timers[_t_computeLive].seconds() + 
 110        timers[_t_regAllocSplit].seconds() +    
 111        timers[_t_postAllocCopyRemoval].seconds() +        
 112        timers[_t_chaitinCompact].seconds() + 
 113        timers[_t_chaitinCoalesce].seconds() + 
 114        timers[_t_chaitinCacheLRG].seconds() + 
 115        timers[_t_chaitinSimplify].seconds() + 
 116        timers[_t_chaitinSelect].seconds() + 
 117        timers[_t_fixupSpills].seconds());
 118     if (other > 0) {
 119       tty->print_cr("         Other:              %6.3f s", other);
 120     }
 121   }
 122   tty->print_cr ("       Block Ordering:     %6.3f s", timers[_t_blockOrdering].seconds());
 123   tty->print_cr ("       Peephole:           %6.3f s", timers[_t_peephole].seconds());
 124   if (Matcher::require_postalloc_expand) {
 125     tty->print_cr ("       Postalloc Expand:   %6.3f s", timers[_t_postalloc_expand].seconds());
 126   }
 127   tty->print_cr ("       Code Emission:        %6.3f s", timers[_t_output].seconds());
 128   tty->print_cr ("         Insn Scheduling:    %6.3f s", timers[_t_instrSched].seconds());
 129   tty->print_cr ("         Build OOP maps:     %6.3f s", timers[_t_buildOopMaps].seconds());
 130   tty->print_cr ("       Code Installation:  %6.3f s", timers[_t_registerMethod].seconds());
 131   
 132   if( timers[_t_temporaryTimer1].seconds() > 0 ) {
 133     tty->cr();
 134     tty->print_cr ("       Temp Timer 1:       %6.3f s", timers[_t_temporaryTimer1].seconds());
 135   }
 136   if( timers[_t_temporaryTimer2].seconds() > 0 ) {
 137     tty->cr();
 138     tty->print_cr ("       Temp Timer 2:       %6.3f s", timers[_t_temporaryTimer2].seconds());
 139   }
 140   
 141    double other = Phase::_t_totalCompilation.seconds() - 
 142       (timers[_t_parser].seconds() + 
 143        timers[_t_optimizer].seconds() + 
 144        timers[_t_matcher].seconds() + 
 145        timers[_t_scheduler].seconds() + 
 146        timers[_t_registerAllocation].seconds() + 
 147        timers[_t_blockOrdering].seconds() + 
 148        timers[_t_peephole].seconds() + 
 149        timers[_t_postalloc_expand].seconds() + 
 150        timers[_t_output].seconds() +    
 151        timers[_t_registerMethod].seconds() +        
 152        timers[_t_temporaryTimer1].seconds() +        
 153        timers[_t_temporaryTimer2].seconds());
 154     if (other > 0) {
 155       tty->print_cr("       Other:              %6.3f s", other);
 156     }  
 157   
 158 }
 159 
 160