Tue, 28 Oct 2014 18:41:34 +0400
8059216: Make PrintGCApplicationStoppedTime print information about stopping threads
Reviewed-by: dholmes, brutisso
1 /*
2 * Copyright (c) 2003, 2013, 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 */
25 #include "precompiled.hpp"
26 #include "classfile/classLoader.hpp"
27 #include "services/attachListener.hpp"
28 #include "services/management.hpp"
29 #include "services/runtimeService.hpp"
30 #include "utilities/dtrace.hpp"
31 #include "utilities/exceptions.hpp"
32 #include "utilities/macros.hpp"
34 #ifndef USDT2
35 HS_DTRACE_PROBE_DECL(hs_private, safepoint__begin);
36 HS_DTRACE_PROBE_DECL(hs_private, safepoint__end);
37 #endif /* !USDT2 */
39 #if INCLUDE_MANAGEMENT
40 TimeStamp RuntimeService::_app_timer;
41 TimeStamp RuntimeService::_safepoint_timer;
42 PerfCounter* RuntimeService::_sync_time_ticks = NULL;
43 PerfCounter* RuntimeService::_total_safepoints = NULL;
44 PerfCounter* RuntimeService::_safepoint_time_ticks = NULL;
45 PerfCounter* RuntimeService::_application_time_ticks = NULL;
46 PerfCounter* RuntimeService::_thread_interrupt_signaled_count = NULL;
47 PerfCounter* RuntimeService::_interrupted_before_count = NULL;
48 PerfCounter* RuntimeService::_interrupted_during_count = NULL;
49 double RuntimeService::_last_safepoint_sync_time_sec = 0.0;
51 void RuntimeService::init() {
52 // Make sure the VM version is initialized
53 Abstract_VM_Version::initialize();
55 if (UsePerfData) {
56 EXCEPTION_MARK;
58 _sync_time_ticks =
59 PerfDataManager::create_counter(SUN_RT, "safepointSyncTime",
60 PerfData::U_Ticks, CHECK);
62 _total_safepoints =
63 PerfDataManager::create_counter(SUN_RT, "safepoints",
64 PerfData::U_Events, CHECK);
66 _safepoint_time_ticks =
67 PerfDataManager::create_counter(SUN_RT, "safepointTime",
68 PerfData::U_Ticks, CHECK);
70 _application_time_ticks =
71 PerfDataManager::create_counter(SUN_RT, "applicationTime",
72 PerfData::U_Ticks, CHECK);
75 // create performance counters for jvm_version and its capabilities
76 PerfDataManager::create_constant(SUN_RT, "jvmVersion", PerfData::U_None,
77 (jlong) Abstract_VM_Version::jvm_version(), CHECK);
79 // I/O interruption related counters
81 // thread signaling via os::interrupt()
83 _thread_interrupt_signaled_count =
84 PerfDataManager::create_counter(SUN_RT,
85 "threadInterruptSignaled", PerfData::U_Events, CHECK);
87 // OS_INTRPT via "check before" in _INTERRUPTIBLE
89 _interrupted_before_count =
90 PerfDataManager::create_counter(SUN_RT, "interruptedBeforeIO",
91 PerfData::U_Events, CHECK);
93 // OS_INTRPT via "check during" in _INTERRUPTIBLE
95 _interrupted_during_count =
96 PerfDataManager::create_counter(SUN_RT, "interruptedDuringIO",
97 PerfData::U_Events, CHECK);
99 // The capabilities counter is a binary representation of the VM capabilities in string.
100 // This string respresentation simplifies the implementation of the client side
101 // to parse the value.
102 char capabilities[65];
103 size_t len = sizeof(capabilities);
104 memset((void*) capabilities, '0', len);
105 capabilities[len-1] = '\0';
106 capabilities[0] = AttachListener::is_attach_supported() ? '1' : '0';
107 #if INCLUDE_SERVICES
108 capabilities[1] = '1';
109 #endif // INCLUDE_SERVICES
110 PerfDataManager::create_string_constant(SUN_RT, "jvmCapabilities",
111 capabilities, CHECK);
112 }
113 }
115 void RuntimeService::record_safepoint_begin() {
116 #ifndef USDT2
117 HS_DTRACE_PROBE(hs_private, safepoint__begin);
118 #else /* USDT2 */
119 HS_PRIVATE_SAFEPOINT_BEGIN();
120 #endif /* USDT2 */
122 // Print the time interval in which the app was executing
123 if (PrintGCApplicationConcurrentTime && _app_timer.is_updated()) {
124 gclog_or_tty->date_stamp(PrintGCDateStamps);
125 gclog_or_tty->stamp(PrintGCTimeStamps);
126 gclog_or_tty->print_cr("Application time: %3.7f seconds",
127 last_application_time_sec());
128 }
130 // update the time stamp to begin recording safepoint time
131 _safepoint_timer.update();
132 _last_safepoint_sync_time_sec = 0.0;
133 if (UsePerfData) {
134 _total_safepoints->inc();
135 if (_app_timer.is_updated()) {
136 _application_time_ticks->inc(_app_timer.ticks_since_update());
137 }
138 }
139 }
141 void RuntimeService::record_safepoint_synchronized() {
142 if (UsePerfData) {
143 _sync_time_ticks->inc(_safepoint_timer.ticks_since_update());
144 }
145 if (PrintGCApplicationStoppedTime) {
146 _last_safepoint_sync_time_sec = last_safepoint_time_sec();
147 }
148 }
150 void RuntimeService::record_safepoint_end() {
151 #ifndef USDT2
152 HS_DTRACE_PROBE(hs_private, safepoint__end);
153 #else /* USDT2 */
154 HS_PRIVATE_SAFEPOINT_END();
155 #endif /* USDT2 */
157 // Print the time interval for which the app was stopped
158 // during the current safepoint operation.
159 if (PrintGCApplicationStoppedTime) {
160 gclog_or_tty->date_stamp(PrintGCDateStamps);
161 gclog_or_tty->stamp(PrintGCTimeStamps);
162 gclog_or_tty->print_cr("Total time for which application threads "
163 "were stopped: %3.7f seconds, "
164 "Stopping threads took: %3.7f seconds",
165 last_safepoint_time_sec(),
166 _last_safepoint_sync_time_sec);
167 }
169 // update the time stamp to begin recording app time
170 _app_timer.update();
171 if (UsePerfData) {
172 _safepoint_time_ticks->inc(_safepoint_timer.ticks_since_update());
173 }
174 }
176 void RuntimeService::record_application_start() {
177 // update the time stamp to begin recording app time
178 _app_timer.update();
179 }
181 // Don't need to record application end because we currently
182 // exit at a safepoint and record_safepoint_begin() handles updating
183 // the application time counter at VM exit.
185 jlong RuntimeService::safepoint_sync_time_ms() {
186 return UsePerfData ?
187 Management::ticks_to_ms(_sync_time_ticks->get_value()) : -1;
188 }
190 jlong RuntimeService::safepoint_count() {
191 return UsePerfData ?
192 _total_safepoints->get_value() : -1;
193 }
194 jlong RuntimeService::safepoint_time_ms() {
195 return UsePerfData ?
196 Management::ticks_to_ms(_safepoint_time_ticks->get_value()) : -1;
197 }
199 jlong RuntimeService::application_time_ms() {
200 return UsePerfData ?
201 Management::ticks_to_ms(_application_time_ticks->get_value()) : -1;
202 }
204 void RuntimeService::record_interrupted_before_count() {
205 if (UsePerfData) {
206 _interrupted_before_count->inc();
207 }
208 }
210 void RuntimeService::record_interrupted_during_count() {
211 if (UsePerfData) {
212 _interrupted_during_count->inc();
213 }
214 }
216 void RuntimeService::record_thread_interrupt_signaled_count() {
217 if (UsePerfData) {
218 _thread_interrupt_signaled_count->inc();
219 }
220 }
222 #endif // INCLUDE_MANAGEMENT