1 /*
   2  * Copyright (c) 2012, 2018, 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 "gc_implementation/shared/gcTimer.hpp"
  27 #include "gc_implementation/shared/gcTrace.hpp"
  28 #include "gc_implementation/shenandoah/shenandoahHeap.inline.hpp"
  29 #include "gc_implementation/shenandoah/shenandoahGCTraceTime.hpp"
  30 #include "runtime/globals.hpp"
  31 #include "runtime/os.hpp"
  32 #include "runtime/safepoint.hpp"
  33 #include "runtime/thread.inline.hpp"
  34 #include "runtime/timer.hpp"
  35 #include "utilities/ostream.hpp"
  36 #include "utilities/ticks.hpp"
  37 
  38 ShenandoahGCTraceTime::ShenandoahGCTraceTime(const char* title, bool doit, GCTimer* timer, GCId gc_id, bool print_heap) :
  39     _title(title), _doit(doit), _timer(timer), _start_counter(), _heap(ShenandoahHeap::heap()), _print_heap(print_heap), _gc_id(gc_id) {
  40   if (_doit || _timer != NULL) {
  41     _start_counter.stamp();
  42   }
  43 
  44   if (_timer != NULL) {
  45     _timer->register_gc_phase_start(title, _start_counter);
  46   }
  47 
  48   if (_doit) {
  49     _bytes_before = _heap->used();
  50 
  51     gclog_or_tty->date_stamp(PrintGCDateStamps);
  52     gclog_or_tty->stamp(PrintGCTimeStamps);
  53     if (PrintGCID && !_gc_id.is_undefined()) {
  54       gclog_or_tty->print("#%u: ", _gc_id.id());
  55     }
  56     gclog_or_tty->print("[%s", title);
  57 
  58     // Detailed view prints the "start" message
  59     if (PrintGCDetails) {
  60       gclog_or_tty->print_cr(", start]");
  61     }
  62 
  63     gclog_or_tty->flush();
  64     gclog_or_tty->inc();
  65   }
  66 }
  67 
  68 ShenandoahGCTraceTime::~ShenandoahGCTraceTime() {
  69   Ticks stop_counter;
  70 
  71   if (_doit || _timer != NULL) {
  72     stop_counter.stamp();
  73   }
  74 
  75   if (_timer != NULL) {
  76     _timer->register_gc_phase_end(stop_counter);
  77   }
  78 
  79   if (_doit) {
  80     const Tickspan duration = stop_counter - _start_counter;
  81     double secs = duration.seconds();
  82 
  83     size_t bytes_after = _heap->used();
  84     size_t capacity = _heap->capacity();
  85 
  86     // Detailed view has to restart the logging here, because "start" was printed
  87     if (PrintGCDetails) {
  88       gclog_or_tty->date_stamp(PrintGCDateStamps);
  89       gclog_or_tty->stamp(PrintGCTimeStamps);
  90       if (PrintGCID && !_gc_id.is_undefined()) {
  91         gclog_or_tty->print("#%u: ", _gc_id.id());
  92       }
  93       gclog_or_tty->print("[%s", _title);
  94     }
  95 
  96     if (_print_heap) {
  97       gclog_or_tty->print(" " SIZE_FORMAT "%s->" SIZE_FORMAT "%s(" SIZE_FORMAT  "%s)",
  98                           byte_size_in_proper_unit(_bytes_before),
  99                           proper_unit_for_byte_size(_bytes_before),
 100                           byte_size_in_proper_unit(bytes_after),
 101                           proper_unit_for_byte_size(bytes_after),
 102                           byte_size_in_proper_unit(capacity),
 103                           proper_unit_for_byte_size(capacity));
 104     }
 105 
 106     gclog_or_tty->dec();
 107     gclog_or_tty->print_cr(", %.3f ms]", secs * 1000);
 108     gclog_or_tty->flush();
 109   }
 110 }