src/share/vm/runtime/timer.cpp

Mon, 12 Aug 2019 18:30:40 +0300

author
apetushkov
date
Mon, 12 Aug 2019 18:30:40 +0300
changeset 9858
b985cbb00e68
parent 6680
78bbf4d43a14
child 6876
710a3c8b516e
permissions
-rw-r--r--

8223147: JFR Backport
8199712: Flight Recorder
8203346: JFR: Inconsistent signature of jfr_add_string_constant
8195817: JFR.stop should require name of recording
8195818: JFR.start should increase autogenerated name by one
8195819: Remove recording=x from jcmd JFR.check output
8203921: JFR thread sampling is missing fixes from JDK-8194552
8203929: Limit amount of data for JFR.dump
8203664: JFR start failure after AppCDS archive created with JFR StartFlightRecording
8003209: JFR events for network utilization
8207392: [PPC64] Implement JFR profiling
8202835: jfr/event/os/TestSystemProcess.java fails on missing events
Summary: Backport JFR from JDK11. Initial integration
Reviewed-by: neugens

duke@435 1 /*
drchase@6680 2 * Copyright (c) 1997, 2014, Oracle and/or its affiliates. All rights reserved.
duke@435 3 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
duke@435 4 *
duke@435 5 * This code is free software; you can redistribute it and/or modify it
duke@435 6 * under the terms of the GNU General Public License version 2 only, as
duke@435 7 * published by the Free Software Foundation.
duke@435 8 *
duke@435 9 * This code is distributed in the hope that it will be useful, but WITHOUT
duke@435 10 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
duke@435 11 * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License
duke@435 12 * version 2 for more details (a copy is included in the LICENSE file that
duke@435 13 * accompanied this code).
duke@435 14 *
duke@435 15 * You should have received a copy of the GNU General Public License version
duke@435 16 * 2 along with this work; if not, write to the Free Software Foundation,
duke@435 17 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
duke@435 18 *
trims@1907 19 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
trims@1907 20 * or visit www.oracle.com if you need additional information or have any
trims@1907 21 * questions.
duke@435 22 *
duke@435 23 */
duke@435 24
stefank@2314 25 #include "precompiled.hpp"
stefank@2314 26 #include "oops/oop.inline.hpp"
stefank@2314 27 #include "runtime/timer.hpp"
stefank@2314 28 #include "utilities/ostream.hpp"
stefank@2314 29 #ifdef TARGET_OS_FAMILY_linux
stefank@2314 30 # include "os_linux.inline.hpp"
stefank@2314 31 #endif
stefank@2314 32 #ifdef TARGET_OS_FAMILY_solaris
stefank@2314 33 # include "os_solaris.inline.hpp"
stefank@2314 34 #endif
stefank@2314 35 #ifdef TARGET_OS_FAMILY_windows
stefank@2314 36 # include "os_windows.inline.hpp"
stefank@2314 37 #endif
goetz@6461 38 #ifdef TARGET_OS_FAMILY_aix
goetz@6461 39 # include "os_aix.inline.hpp"
goetz@6461 40 #endif
never@3156 41 #ifdef TARGET_OS_FAMILY_bsd
never@3156 42 # include "os_bsd.inline.hpp"
never@3156 43 #endif
duke@435 44
sla@5237 45 double TimeHelper::counter_to_seconds(jlong counter) {
sla@5237 46 double count = (double) counter;
sla@5237 47 double freq = (double) os::elapsed_frequency();
sla@5237 48 return counter/freq;
sla@5237 49 }
duke@435 50
duke@435 51 void elapsedTimer::add(elapsedTimer t) {
duke@435 52 _counter += t._counter;
duke@435 53 }
duke@435 54
duke@435 55 void elapsedTimer::start() {
duke@435 56 if (!_active) {
duke@435 57 _active = true;
duke@435 58 _start_counter = os::elapsed_counter();
duke@435 59 }
duke@435 60 }
duke@435 61
duke@435 62 void elapsedTimer::stop() {
duke@435 63 if (_active) {
duke@435 64 _counter += os::elapsed_counter() - _start_counter;
duke@435 65 _active = false;
duke@435 66 }
duke@435 67 }
duke@435 68
duke@435 69 double elapsedTimer::seconds() const {
sla@5237 70 return TimeHelper::counter_to_seconds(_counter);
duke@435 71 }
duke@435 72
duke@435 73 jlong elapsedTimer::milliseconds() const {
duke@435 74 jlong ticks_per_ms = os::elapsed_frequency() / 1000;
duke@435 75 return _counter / ticks_per_ms;
duke@435 76 }
duke@435 77
duke@435 78 jlong elapsedTimer::active_ticks() const {
duke@435 79 if (!_active) {
duke@435 80 return ticks();
duke@435 81 }
duke@435 82 jlong counter = _counter + os::elapsed_counter() - _start_counter;
duke@435 83 return counter;
duke@435 84 }
duke@435 85
duke@435 86 void TimeStamp::update_to(jlong ticks) {
duke@435 87 _counter = ticks;
duke@435 88 if (_counter == 0) _counter = 1;
duke@435 89 assert(is_updated(), "must not look clear");
duke@435 90 }
duke@435 91
duke@435 92 void TimeStamp::update() {
duke@435 93 update_to(os::elapsed_counter());
duke@435 94 }
duke@435 95
duke@435 96 double TimeStamp::seconds() const {
duke@435 97 assert(is_updated(), "must not be clear");
duke@435 98 jlong new_count = os::elapsed_counter();
sla@5237 99 return TimeHelper::counter_to_seconds(new_count - _counter);
duke@435 100 }
duke@435 101
duke@435 102 jlong TimeStamp::milliseconds() const {
duke@435 103 assert(is_updated(), "must not be clear");
duke@435 104
duke@435 105 jlong new_count = os::elapsed_counter();
duke@435 106 jlong count = new_count - _counter;
duke@435 107 jlong ticks_per_ms = os::elapsed_frequency() / 1000;
duke@435 108 return count / ticks_per_ms;
duke@435 109 }
duke@435 110
duke@435 111 jlong TimeStamp::ticks_since_update() const {
duke@435 112 assert(is_updated(), "must not be clear");
duke@435 113 return os::elapsed_counter() - _counter;
duke@435 114 }
duke@435 115
duke@435 116 TraceTime::TraceTime(const char* title,
sla@5237 117 bool doit) {
duke@435 118 _active = doit;
duke@435 119 _verbose = true;
duke@435 120
duke@435 121 if (_active) {
duke@435 122 _accum = NULL;
sla@5237 123 tty->stamp(PrintGCTimeStamps);
sla@5237 124 tty->print("[%s", title);
sla@5237 125 tty->flush();
duke@435 126 _t.start();
duke@435 127 }
duke@435 128 }
duke@435 129
duke@435 130 TraceTime::TraceTime(const char* title,
duke@435 131 elapsedTimer* accumulator,
duke@435 132 bool doit,
sla@5237 133 bool verbose) {
duke@435 134 _active = doit;
duke@435 135 _verbose = verbose;
duke@435 136 if (_active) {
duke@435 137 if (_verbose) {
sla@5237 138 tty->stamp(PrintGCTimeStamps);
sla@5237 139 tty->print("[%s", title);
sla@5237 140 tty->flush();
duke@435 141 }
duke@435 142 _accum = accumulator;
duke@435 143 _t.start();
duke@435 144 }
duke@435 145 }
duke@435 146
duke@435 147 TraceTime::~TraceTime() {
duke@435 148 if (_active) {
duke@435 149 _t.stop();
duke@435 150 if (_accum!=NULL) _accum->add(_t);
duke@435 151 if (_verbose) {
sla@5237 152 tty->print_cr(", %3.7f secs]", _t.seconds());
sla@5237 153 tty->flush();
duke@435 154 }
duke@435 155 }
duke@435 156 }
duke@435 157
duke@435 158 TraceCPUTime::TraceCPUTime(bool doit,
duke@435 159 bool print_cr,
duke@435 160 outputStream *logfile) :
duke@435 161 _active(doit),
duke@435 162 _print_cr(print_cr),
duke@435 163 _starting_user_time(0.0),
duke@435 164 _starting_system_time(0.0),
duke@435 165 _starting_real_time(0.0),
duke@435 166 _logfile(logfile),
duke@435 167 _error(false) {
duke@435 168 if (_active) {
duke@435 169 if (logfile != NULL) {
duke@435 170 _logfile = logfile;
duke@435 171 } else {
duke@435 172 _logfile = tty;
duke@435 173 }
duke@435 174
duke@435 175 _error = !os::getTimesSecs(&_starting_real_time,
duke@435 176 &_starting_user_time,
duke@435 177 &_starting_system_time);
duke@435 178 }
duke@435 179 }
duke@435 180
duke@435 181 TraceCPUTime::~TraceCPUTime() {
duke@435 182 if (_active) {
duke@435 183 bool valid = false;
duke@435 184 if (!_error) {
duke@435 185 double real_secs; // walk clock time
duke@435 186 double system_secs; // system time
duke@435 187 double user_secs; // user time for all threads
duke@435 188
duke@435 189 double real_time, user_time, system_time;
duke@435 190 valid = os::getTimesSecs(&real_time, &user_time, &system_time);
duke@435 191 if (valid) {
duke@435 192
duke@435 193 user_secs = user_time - _starting_user_time;
duke@435 194 system_secs = system_time - _starting_system_time;
duke@435 195 real_secs = real_time - _starting_real_time;
duke@435 196
duke@435 197 _logfile->print(" [Times: user=%3.2f sys=%3.2f, real=%3.2f secs] ",
duke@435 198 user_secs, system_secs, real_secs);
duke@435 199
duke@435 200 } else {
duke@435 201 _logfile->print("[Invalid result in TraceCPUTime]");
duke@435 202 }
duke@435 203 } else {
duke@435 204 _logfile->print("[Error in TraceCPUTime]");
duke@435 205 }
johnc@4326 206 if (_print_cr) {
drchase@6680 207 _logfile->cr();
duke@435 208 }
johnc@4326 209 _logfile->flush();
duke@435 210 }
duke@435 211 }

mercurial