author | kvn |
Mon, 10 Jul 2017 14:28:40 -0700 | |
changeset 45806 | 36707109c109 |
parent 37161 | e881f320966e |
permissions | -rw-r--r-- |
1 | 1 |
/* |
36178
9739f8c767da
8148630: Convert TraceStartupTime to Unified Logging
rprotacio
parents:
35061
diff
changeset
|
2 |
* Copyright (c) 1997, 2016, Oracle and/or its affiliates. All rights reserved. |
1 | 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 |
* |
|
5547
f4b087cbb361
6941466: Oracle rebranding changes for Hotspot repositories
trims
parents:
1
diff
changeset
|
19 |
* Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA |
f4b087cbb361
6941466: Oracle rebranding changes for Hotspot repositories
trims
parents:
1
diff
changeset
|
20 |
* or visit www.oracle.com if you need additional information or have any |
f4b087cbb361
6941466: Oracle rebranding changes for Hotspot repositories
trims
parents:
1
diff
changeset
|
21 |
* questions. |
1 | 22 |
* |
23 |
*/ |
|
24 |
||
7397 | 25 |
#include "precompiled.hpp" |
36178
9739f8c767da
8148630: Convert TraceStartupTime to Unified Logging
rprotacio
parents:
35061
diff
changeset
|
26 |
#include "logging/log.hpp" |
7397 | 27 |
#include "oops/oop.inline.hpp" |
28 |
#include "runtime/timer.hpp" |
|
29 |
#include "utilities/ostream.hpp" |
|
1 | 30 |
|
18025 | 31 |
double TimeHelper::counter_to_seconds(jlong counter) { |
32 |
double freq = (double) os::elapsed_frequency(); |
|
30608
d79880a5cf2f
8079561: Add a method to convert counters to milliseconds
brutisso
parents:
25468
diff
changeset
|
33 |
return counter / freq; |
d79880a5cf2f
8079561: Add a method to convert counters to milliseconds
brutisso
parents:
25468
diff
changeset
|
34 |
} |
d79880a5cf2f
8079561: Add a method to convert counters to milliseconds
brutisso
parents:
25468
diff
changeset
|
35 |
|
d79880a5cf2f
8079561: Add a method to convert counters to milliseconds
brutisso
parents:
25468
diff
changeset
|
36 |
double TimeHelper::counter_to_millis(jlong counter) { |
d79880a5cf2f
8079561: Add a method to convert counters to milliseconds
brutisso
parents:
25468
diff
changeset
|
37 |
return counter_to_seconds(counter) * 1000.0; |
18025 | 38 |
} |
1 | 39 |
|
33160
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
40 |
elapsedTimer::elapsedTimer(jlong time, jlong timeUnitsPerSecond) { |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
41 |
_active = false; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
42 |
jlong osTimeUnitsPerSecond = os::elapsed_frequency(); |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
43 |
assert(osTimeUnitsPerSecond % 1000 == 0, "must be"); |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
44 |
assert(timeUnitsPerSecond % 1000 == 0, "must be"); |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
45 |
while (osTimeUnitsPerSecond < timeUnitsPerSecond) { |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
46 |
timeUnitsPerSecond /= 1000; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
47 |
time *= 1000; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
48 |
} |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
49 |
while (osTimeUnitsPerSecond > timeUnitsPerSecond) { |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
50 |
timeUnitsPerSecond *= 1000; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
51 |
time /= 1000; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
52 |
} |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
53 |
_counter = time; |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
54 |
} |
c59f1676d27e
8136421: JEP 243: Java-Level JVM Compiler Interface
twisti
parents:
31334
diff
changeset
|
55 |
|
1 | 56 |
void elapsedTimer::add(elapsedTimer t) { |
57 |
_counter += t._counter; |
|
58 |
} |
|
59 |
||
60 |
void elapsedTimer::start() { |
|
61 |
if (!_active) { |
|
62 |
_active = true; |
|
63 |
_start_counter = os::elapsed_counter(); |
|
64 |
} |
|
65 |
} |
|
66 |
||
67 |
void elapsedTimer::stop() { |
|
68 |
if (_active) { |
|
69 |
_counter += os::elapsed_counter() - _start_counter; |
|
70 |
_active = false; |
|
71 |
} |
|
72 |
} |
|
73 |
||
74 |
double elapsedTimer::seconds() const { |
|
18025 | 75 |
return TimeHelper::counter_to_seconds(_counter); |
1 | 76 |
} |
77 |
||
78 |
jlong elapsedTimer::milliseconds() const { |
|
31334
d55c96b36b5f
8085975: Fix warning "converting to jlong from double" of gcc 4.1.2 after 8079561
goetz
parents:
30608
diff
changeset
|
79 |
return (jlong)TimeHelper::counter_to_millis(_counter); |
1 | 80 |
} |
81 |
||
82 |
jlong elapsedTimer::active_ticks() const { |
|
83 |
if (!_active) { |
|
84 |
return ticks(); |
|
85 |
} |
|
86 |
jlong counter = _counter + os::elapsed_counter() - _start_counter; |
|
87 |
return counter; |
|
88 |
} |
|
89 |
||
90 |
void TimeStamp::update_to(jlong ticks) { |
|
91 |
_counter = ticks; |
|
92 |
if (_counter == 0) _counter = 1; |
|
93 |
assert(is_updated(), "must not look clear"); |
|
94 |
} |
|
95 |
||
96 |
void TimeStamp::update() { |
|
97 |
update_to(os::elapsed_counter()); |
|
98 |
} |
|
99 |
||
100 |
double TimeStamp::seconds() const { |
|
101 |
assert(is_updated(), "must not be clear"); |
|
102 |
jlong new_count = os::elapsed_counter(); |
|
18025 | 103 |
return TimeHelper::counter_to_seconds(new_count - _counter); |
1 | 104 |
} |
105 |
||
106 |
jlong TimeStamp::milliseconds() const { |
|
107 |
assert(is_updated(), "must not be clear"); |
|
108 |
jlong new_count = os::elapsed_counter(); |
|
31334
d55c96b36b5f
8085975: Fix warning "converting to jlong from double" of gcc 4.1.2 after 8079561
goetz
parents:
30608
diff
changeset
|
109 |
return (jlong)TimeHelper::counter_to_millis(new_count - _counter); |
1 | 110 |
} |
111 |
||
112 |
jlong TimeStamp::ticks_since_update() const { |
|
113 |
assert(is_updated(), "must not be clear"); |
|
114 |
return os::elapsed_counter() - _counter; |
|
115 |
} |
|
116 |
||
117 |
TraceCPUTime::TraceCPUTime(bool doit, |
|
118 |
bool print_cr, |
|
119 |
outputStream *logfile) : |
|
120 |
_active(doit), |
|
121 |
_print_cr(print_cr), |
|
122 |
_starting_user_time(0.0), |
|
123 |
_starting_system_time(0.0), |
|
124 |
_starting_real_time(0.0), |
|
125 |
_logfile(logfile), |
|
126 |
_error(false) { |
|
127 |
if (_active) { |
|
128 |
if (logfile != NULL) { |
|
129 |
_logfile = logfile; |
|
130 |
} else { |
|
131 |
_logfile = tty; |
|
132 |
} |
|
133 |
||
134 |
_error = !os::getTimesSecs(&_starting_real_time, |
|
135 |
&_starting_user_time, |
|
136 |
&_starting_system_time); |
|
137 |
} |
|
138 |
} |
|
139 |
||
140 |
TraceCPUTime::~TraceCPUTime() { |
|
141 |
if (_active) { |
|
142 |
bool valid = false; |
|
143 |
if (!_error) { |
|
144 |
double real_secs; // walk clock time |
|
145 |
double system_secs; // system time |
|
146 |
double user_secs; // user time for all threads |
|
147 |
||
148 |
double real_time, user_time, system_time; |
|
149 |
valid = os::getTimesSecs(&real_time, &user_time, &system_time); |
|
150 |
if (valid) { |
|
151 |
||
152 |
user_secs = user_time - _starting_user_time; |
|
153 |
system_secs = system_time - _starting_system_time; |
|
154 |
real_secs = real_time - _starting_real_time; |
|
155 |
||
23223 | 156 |
_logfile->print(" [Times: user=%3.2f sys=%3.2f real=%3.2f secs] ", |
1 | 157 |
user_secs, system_secs, real_secs); |
158 |
||
159 |
} else { |
|
160 |
_logfile->print("[Invalid result in TraceCPUTime]"); |
|
161 |
} |
|
162 |
} else { |
|
163 |
_logfile->print("[Error in TraceCPUTime]"); |
|
164 |
} |
|
14634
fdd9909928ae
8004170: G1: Verbose GC output is not getting flushed to log file using JDK 8
johnc
parents:
13963
diff
changeset
|
165 |
if (_print_cr) { |
24424
2658d7834c6e
8037816: Fix for 8036122 breaks build with Xcode5/clang
drchase
parents:
23223
diff
changeset
|
166 |
_logfile->cr(); |
1 | 167 |
} |
14634
fdd9909928ae
8004170: G1: Verbose GC output is not getting flushed to log file using JDK 8
johnc
parents:
13963
diff
changeset
|
168 |
_logfile->flush(); |
1 | 169 |
} |
170 |
} |
|
37161
e881f320966e
8150015: Integrate TraceTime with Unified Logging more seamlessly
rehn
parents:
37043
diff
changeset
|
171 |