annotate src/share/vm/runtime/timer.cpp @ 18096:ca6d25be853b jdk8u25-b13

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