duke@435: /*
drchase@6680:  * Copyright (c) 1997, 2014, Oracle and/or its affiliates. All rights reserved.
duke@435:  * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
duke@435:  *
duke@435:  * This code is free software; you can redistribute it and/or modify it
duke@435:  * under the terms of the GNU General Public License version 2 only, as
duke@435:  * published by the Free Software Foundation.
duke@435:  *
duke@435:  * This code is distributed in the hope that it will be useful, but WITHOUT
duke@435:  * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
duke@435:  * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
duke@435:  * version 2 for more details (a copy is included in the LICENSE file that
duke@435:  * accompanied this code).
duke@435:  *
duke@435:  * You should have received a copy of the GNU General Public License version
duke@435:  * 2 along with this work; if not, write to the Free Software Foundation,
duke@435:  * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
duke@435:  *
trims@1907:  * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
trims@1907:  * or visit www.oracle.com if you need additional information or have any
trims@1907:  * questions.
duke@435:  *
duke@435:  */
duke@435: 
stefank@2314: #include "precompiled.hpp"
stefank@2314: #include "oops/oop.inline.hpp"
stefank@2314: #include "runtime/timer.hpp"
stefank@2314: #include "utilities/ostream.hpp"
stefank@2314: #ifdef TARGET_OS_FAMILY_linux
stefank@2314: # include "os_linux.inline.hpp"
stefank@2314: #endif
stefank@2314: #ifdef TARGET_OS_FAMILY_solaris
stefank@2314: # include "os_solaris.inline.hpp"
stefank@2314: #endif
stefank@2314: #ifdef TARGET_OS_FAMILY_windows
stefank@2314: # include "os_windows.inline.hpp"
stefank@2314: #endif
goetz@6461: #ifdef TARGET_OS_FAMILY_aix
goetz@6461: # include "os_aix.inline.hpp"
goetz@6461: #endif
never@3156: #ifdef TARGET_OS_FAMILY_bsd
never@3156: # include "os_bsd.inline.hpp"
never@3156: #endif
duke@435: 
sla@5237: double TimeHelper::counter_to_seconds(jlong counter) {
sla@5237:   double count = (double) counter;
sla@5237:   double freq  = (double) os::elapsed_frequency();
sla@5237:   return counter/freq;
sla@5237: }
duke@435: 
duke@435: void elapsedTimer::add(elapsedTimer t) {
duke@435:   _counter += t._counter;
duke@435: }
duke@435: 
duke@435: void elapsedTimer::start() {
duke@435:   if (!_active) {
duke@435:     _active = true;
duke@435:     _start_counter = os::elapsed_counter();
duke@435:   }
duke@435: }
duke@435: 
duke@435: void elapsedTimer::stop() {
duke@435:   if (_active) {
duke@435:     _counter += os::elapsed_counter() - _start_counter;
duke@435:     _active = false;
duke@435:   }
duke@435: }
duke@435: 
duke@435: double elapsedTimer::seconds() const {
sla@5237:  return TimeHelper::counter_to_seconds(_counter);
duke@435: }
duke@435: 
duke@435: jlong elapsedTimer::milliseconds() const {
duke@435:   jlong ticks_per_ms = os::elapsed_frequency() / 1000;
duke@435:   return _counter / ticks_per_ms;
duke@435: }
duke@435: 
duke@435: jlong elapsedTimer::active_ticks() const {
duke@435:   if (!_active) {
duke@435:     return ticks();
duke@435:   }
duke@435:   jlong counter = _counter + os::elapsed_counter() - _start_counter;
duke@435:   return counter;
duke@435: }
duke@435: 
duke@435: void TimeStamp::update_to(jlong ticks) {
duke@435:   _counter = ticks;
duke@435:   if (_counter == 0)  _counter = 1;
duke@435:   assert(is_updated(), "must not look clear");
duke@435: }
duke@435: 
duke@435: void TimeStamp::update() {
duke@435:   update_to(os::elapsed_counter());
duke@435: }
duke@435: 
duke@435: double TimeStamp::seconds() const {
duke@435:   assert(is_updated(), "must not be clear");
duke@435:   jlong new_count = os::elapsed_counter();
sla@5237:   return TimeHelper::counter_to_seconds(new_count - _counter);
duke@435: }
duke@435: 
duke@435: jlong TimeStamp::milliseconds() const {
duke@435:   assert(is_updated(), "must not be clear");
duke@435: 
duke@435:   jlong new_count = os::elapsed_counter();
duke@435:   jlong count = new_count - _counter;
duke@435:   jlong ticks_per_ms = os::elapsed_frequency() / 1000;
duke@435:   return count / ticks_per_ms;
duke@435: }
duke@435: 
duke@435: jlong TimeStamp::ticks_since_update() const {
duke@435:   assert(is_updated(), "must not be clear");
duke@435:   return os::elapsed_counter() - _counter;
duke@435: }
duke@435: 
duke@435: TraceTime::TraceTime(const char* title,
sla@5237:                      bool doit) {
duke@435:   _active   = doit;
duke@435:   _verbose  = true;
duke@435: 
duke@435:   if (_active) {
duke@435:     _accum = NULL;
sla@5237:     tty->stamp(PrintGCTimeStamps);
sla@5237:     tty->print("[%s", title);
sla@5237:     tty->flush();
duke@435:     _t.start();
duke@435:   }
duke@435: }
duke@435: 
duke@435: TraceTime::TraceTime(const char* title,
duke@435:                      elapsedTimer* accumulator,
duke@435:                      bool doit,
sla@5237:                      bool verbose) {
duke@435:   _active = doit;
duke@435:   _verbose = verbose;
duke@435:   if (_active) {
duke@435:     if (_verbose) {
sla@5237:       tty->stamp(PrintGCTimeStamps);
sla@5237:       tty->print("[%s", title);
sla@5237:       tty->flush();
duke@435:     }
duke@435:     _accum = accumulator;
duke@435:     _t.start();
duke@435:   }
duke@435: }
duke@435: 
duke@435: TraceTime::~TraceTime() {
duke@435:   if (_active) {
duke@435:     _t.stop();
duke@435:     if (_accum!=NULL) _accum->add(_t);
duke@435:     if (_verbose) {
sla@5237:       tty->print_cr(", %3.7f secs]", _t.seconds());
sla@5237:       tty->flush();
duke@435:     }
duke@435:   }
duke@435: }
duke@435: 
duke@435: TraceCPUTime::TraceCPUTime(bool doit,
duke@435:                bool print_cr,
duke@435:                outputStream *logfile) :
duke@435:   _active(doit),
duke@435:   _print_cr(print_cr),
duke@435:   _starting_user_time(0.0),
duke@435:   _starting_system_time(0.0),
duke@435:   _starting_real_time(0.0),
duke@435:   _logfile(logfile),
duke@435:   _error(false) {
duke@435:   if (_active) {
duke@435:     if (logfile != NULL) {
duke@435:       _logfile = logfile;
duke@435:     } else {
duke@435:       _logfile = tty;
duke@435:     }
duke@435: 
duke@435:     _error = !os::getTimesSecs(&_starting_real_time,
duke@435:                                &_starting_user_time,
duke@435:                                &_starting_system_time);
duke@435:   }
duke@435: }
duke@435: 
duke@435: TraceCPUTime::~TraceCPUTime() {
duke@435:   if (_active) {
duke@435:     bool valid = false;
duke@435:     if (!_error) {
duke@435:       double real_secs;                 // walk clock time
duke@435:       double system_secs;               // system time
duke@435:       double user_secs;                 // user time for all threads
duke@435: 
duke@435:       double real_time, user_time, system_time;
duke@435:       valid = os::getTimesSecs(&real_time, &user_time, &system_time);
duke@435:       if (valid) {
duke@435: 
duke@435:         user_secs = user_time - _starting_user_time;
duke@435:         system_secs = system_time - _starting_system_time;
duke@435:         real_secs = real_time - _starting_real_time;
duke@435: 
duke@435:         _logfile->print(" [Times: user=%3.2f sys=%3.2f, real=%3.2f secs] ",
duke@435:           user_secs, system_secs, real_secs);
duke@435: 
duke@435:       } else {
duke@435:         _logfile->print("[Invalid result in TraceCPUTime]");
duke@435:       }
duke@435:     } else {
duke@435:       _logfile->print("[Error in TraceCPUTime]");
duke@435:     }
johnc@4326:     if (_print_cr) {
drchase@6680:       _logfile->cr();
duke@435:     }
johnc@4326:     _logfile->flush();
duke@435:   }
duke@435: }