src/share/vm/gc_implementation/g1/g1GCPhaseTimes.hpp

Tue, 19 Mar 2013 00:57:39 -0700

author
johnc
date
Tue, 19 Mar 2013 00:57:39 -0700
changeset 4788
e864cc14ca75
parent 4475
7df93f7c14a5
child 5078
194f52aa2f23
permissions
-rw-r--r--

8009940: G1: assert(_finger == _heap_end) failed, concurrentMark.cpp:809
Summary: Skip reference processing if the global marking stack overflows during remark. Refactor and rename set_phase(); move code that sets the concurrency level into its own routine. Do not call set_phase() from within parallel reference processing; use the concurrency level routine instead. The marking state should only set reset by CMTask[0] during the concurrent phase of the marking cycle; if an overflow occurs at any stage during the remark, the marking state will be reset after reference processing.
Reviewed-by: brutisso, jmasa

brutisso@3923 1 /*
brutisso@3923 2 * Copyright (c) 2012, Oracle and/or its affiliates. All rights reserved.
brutisso@3923 3 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
brutisso@3923 4 *
brutisso@3923 5 * This code is free software; you can redistribute it and/or modify it
brutisso@3923 6 * under the terms of the GNU General Public License version 2 only, as
brutisso@3923 7 * published by the Free Software Foundation.
brutisso@3923 8 *
brutisso@3923 9 * This code is distributed in the hope that it will be useful, but WITHOUT
brutisso@3923 10 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
brutisso@3923 11 * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License
brutisso@3923 12 * version 2 for more details (a copy is included in the LICENSE file that
brutisso@3923 13 * accompanied this code).
brutisso@3923 14 *
brutisso@3923 15 * You should have received a copy of the GNU General Public License version
brutisso@3923 16 * 2 along with this work; if not, write to the Free Software Foundation,
brutisso@3923 17 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
brutisso@3923 18 *
brutisso@3923 19 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
brutisso@3923 20 * or visit www.oracle.com if you need additional information or have any
brutisso@3923 21 * questions.
brutisso@3923 22 *
brutisso@3923 23 */
brutisso@3923 24
brutisso@3923 25 #ifndef SHARE_VM_GC_IMPLEMENTATION_G1_G1GCPHASETIMESLOG_HPP
brutisso@3923 26 #define SHARE_VM_GC_IMPLEMENTATION_G1_G1GCPHASETIMESLOG_HPP
brutisso@3923 27
brutisso@3923 28 #include "memory/allocation.hpp"
brutisso@3923 29 #include "gc_interface/gcCause.hpp"
brutisso@3923 30
brutisso@4015 31 template <class T>
brutisso@4015 32 class WorkerDataArray : public CHeapObj<mtGC> {
brutisso@4015 33 T* _data;
brutisso@4015 34 uint _length;
brutisso@4015 35 const char* _print_format;
brutisso@4015 36 bool _print_sum;
brutisso@4015 37
brutisso@4475 38 NOT_PRODUCT(static const T _uninitialized;)
brutisso@4475 39
brutisso@4015 40 // We are caching the sum and average to only have to calculate them once.
brutisso@4015 41 // This is not done in an MT-safe way. It is intetened to allow single
brutisso@4015 42 // threaded code to call sum() and average() multiple times in any order
brutisso@4015 43 // without having to worry about the cost.
brutisso@4015 44 bool _has_new_data;
brutisso@4015 45 T _sum;
brutisso@4015 46 double _average;
brutisso@4015 47
brutisso@4015 48 public:
brutisso@4015 49 WorkerDataArray(uint length, const char* print_format, bool print_sum = true) :
brutisso@4015 50 _length(length), _print_format(print_format), _print_sum(print_sum), _has_new_data(true) {
brutisso@4015 51 assert(length > 0, "Must have some workers to store data for");
brutisso@4015 52 _data = NEW_C_HEAP_ARRAY(T, _length, mtGC);
brutisso@4015 53 }
brutisso@4015 54
brutisso@4015 55 ~WorkerDataArray() {
brutisso@4015 56 FREE_C_HEAP_ARRAY(T, _data, mtGC);
brutisso@4015 57 }
brutisso@4015 58
brutisso@4015 59 void set(uint worker_i, T value) {
brutisso@4015 60 assert(worker_i < _length, err_msg("Worker %d is greater than max: %d", worker_i, _length));
brutisso@4015 61 assert(_data[worker_i] == (T)-1, err_msg("Overwriting data for worker %d", worker_i));
brutisso@4015 62 _data[worker_i] = value;
brutisso@4015 63 _has_new_data = true;
brutisso@4015 64 }
brutisso@4015 65
brutisso@4015 66 T get(uint worker_i) {
brutisso@4015 67 assert(worker_i < _length, err_msg("Worker %d is greater than max: %d", worker_i, _length));
brutisso@4015 68 assert(_data[worker_i] != (T)-1, err_msg("No data to add to for worker %d", worker_i));
brutisso@4015 69 return _data[worker_i];
brutisso@4015 70 }
brutisso@4015 71
brutisso@4015 72 void add(uint worker_i, T value) {
brutisso@4015 73 assert(worker_i < _length, err_msg("Worker %d is greater than max: %d", worker_i, _length));
brutisso@4015 74 assert(_data[worker_i] != (T)-1, err_msg("No data to add to for worker %d", worker_i));
brutisso@4015 75 _data[worker_i] += value;
brutisso@4015 76 _has_new_data = true;
brutisso@4015 77 }
brutisso@4015 78
brutisso@4015 79 double average(){
brutisso@4015 80 if (_has_new_data) {
brutisso@4015 81 calculate_totals();
brutisso@4015 82 }
brutisso@4015 83 return _average;
brutisso@4015 84 }
brutisso@4015 85
brutisso@4015 86 T sum() {
brutisso@4015 87 if (_has_new_data) {
brutisso@4015 88 calculate_totals();
brutisso@4015 89 }
brutisso@4015 90 return _sum;
brutisso@4015 91 }
brutisso@4015 92
brutisso@4015 93 void print(int level, const char* title);
brutisso@4015 94
brutisso@4015 95 void reset() PRODUCT_RETURN;
brutisso@4015 96 void verify() PRODUCT_RETURN;
brutisso@4015 97
brutisso@4015 98 private:
brutisso@4015 99
brutisso@4015 100 void calculate_totals(){
brutisso@4015 101 _sum = (T)0;
brutisso@4015 102 for (uint i = 0; i < _length; ++i) {
brutisso@4015 103 _sum += _data[i];
brutisso@4015 104 }
brutisso@4015 105 _average = (double)_sum / (double)_length;
brutisso@4015 106 _has_new_data = false;
brutisso@4015 107 }
brutisso@4015 108 };
brutisso@4015 109
jmasa@3924 110 class G1GCPhaseTimes : public CHeapObj<mtGC> {
brutisso@3923 111
brutisso@3923 112 private:
brutisso@3923 113 uint _active_gc_threads;
brutisso@3923 114 uint _max_gc_threads;
brutisso@3923 115
brutisso@4015 116 WorkerDataArray<double> _last_gc_worker_start_times_ms;
brutisso@4015 117 WorkerDataArray<double> _last_ext_root_scan_times_ms;
brutisso@4015 118 WorkerDataArray<double> _last_satb_filtering_times_ms;
brutisso@4015 119 WorkerDataArray<double> _last_update_rs_times_ms;
brutisso@4015 120 WorkerDataArray<int> _last_update_rs_processed_buffers;
brutisso@4015 121 WorkerDataArray<double> _last_scan_rs_times_ms;
brutisso@4015 122 WorkerDataArray<double> _last_obj_copy_times_ms;
brutisso@4015 123 WorkerDataArray<double> _last_termination_times_ms;
brutisso@4015 124 WorkerDataArray<size_t> _last_termination_attempts;
brutisso@4015 125 WorkerDataArray<double> _last_gc_worker_end_times_ms;
brutisso@4015 126 WorkerDataArray<double> _last_gc_worker_times_ms;
brutisso@4015 127 WorkerDataArray<double> _last_gc_worker_other_times_ms;
brutisso@3923 128
brutisso@3923 129 double _cur_collection_par_time_ms;
brutisso@3923 130 double _cur_collection_code_root_fixup_time_ms;
brutisso@3923 131
brutisso@3923 132 double _cur_clear_ct_time_ms;
brutisso@3923 133 double _cur_ref_proc_time_ms;
brutisso@3923 134 double _cur_ref_enq_time_ms;
brutisso@3923 135
brutisso@3923 136 // Card Table Count Cache stats
brutisso@3923 137 double _min_clear_cc_time_ms; // min
brutisso@3923 138 double _max_clear_cc_time_ms; // max
brutisso@3923 139 double _cur_clear_cc_time_ms; // clearing time during current pause
brutisso@3923 140 double _cum_clear_cc_time_ms; // cummulative clearing time
brutisso@3923 141 jlong _num_cc_clears; // number of times the card count cache has been cleared
brutisso@3923 142
brutisso@3923 143 double _cur_collection_start_sec;
brutisso@3923 144 double _root_region_scan_wait_time_ms;
brutisso@3923 145
brutisso@3923 146 double _recorded_young_cset_choice_time_ms;
brutisso@3923 147 double _recorded_non_young_cset_choice_time_ms;
brutisso@3923 148
brutisso@3923 149 double _recorded_young_free_cset_time_ms;
brutisso@3923 150 double _recorded_non_young_free_cset_time_ms;
brutisso@3923 151
brutisso@4015 152 double _cur_verify_before_time_ms;
brutisso@4015 153 double _cur_verify_after_time_ms;
brutisso@4015 154
brutisso@4015 155 // Helper methods for detailed logging
brutisso@4015 156 void print_stats(int level, const char* str, double value);
brutisso@4015 157 void print_stats(int level, const char* str, double value, int workers);
brutisso@3923 158
brutisso@3923 159 public:
brutisso@3923 160 G1GCPhaseTimes(uint max_gc_threads);
brutisso@4015 161 void note_gc_start(uint active_gc_threads);
brutisso@4015 162 void note_gc_end();
brutisso@4015 163 void print(double pause_time_sec);
brutisso@3923 164
brutisso@3923 165 void record_gc_worker_start_time(uint worker_i, double ms) {
brutisso@4015 166 _last_gc_worker_start_times_ms.set(worker_i, ms);
brutisso@3923 167 }
brutisso@3923 168
brutisso@3923 169 void record_ext_root_scan_time(uint worker_i, double ms) {
brutisso@4015 170 _last_ext_root_scan_times_ms.set(worker_i, ms);
brutisso@3923 171 }
brutisso@3923 172
brutisso@3923 173 void record_satb_filtering_time(uint worker_i, double ms) {
brutisso@4015 174 _last_satb_filtering_times_ms.set(worker_i, ms);
brutisso@3923 175 }
brutisso@3923 176
brutisso@3923 177 void record_update_rs_time(uint worker_i, double ms) {
brutisso@4015 178 _last_update_rs_times_ms.set(worker_i, ms);
brutisso@3923 179 }
brutisso@3923 180
brutisso@4015 181 void record_update_rs_processed_buffers(uint worker_i, int processed_buffers) {
brutisso@4015 182 _last_update_rs_processed_buffers.set(worker_i, processed_buffers);
brutisso@3923 183 }
brutisso@3923 184
brutisso@3923 185 void record_scan_rs_time(uint worker_i, double ms) {
brutisso@4015 186 _last_scan_rs_times_ms.set(worker_i, ms);
brutisso@3923 187 }
brutisso@3923 188
brutisso@3923 189 void record_obj_copy_time(uint worker_i, double ms) {
brutisso@4015 190 _last_obj_copy_times_ms.set(worker_i, ms);
brutisso@4015 191 }
brutisso@4015 192
brutisso@4015 193 void add_obj_copy_time(uint worker_i, double ms) {
brutisso@4015 194 _last_obj_copy_times_ms.add(worker_i, ms);
brutisso@3923 195 }
brutisso@3923 196
brutisso@3923 197 void record_termination(uint worker_i, double ms, size_t attempts) {
brutisso@4015 198 _last_termination_times_ms.set(worker_i, ms);
brutisso@4015 199 _last_termination_attempts.set(worker_i, attempts);
brutisso@3923 200 }
brutisso@3923 201
brutisso@3923 202 void record_gc_worker_end_time(uint worker_i, double ms) {
brutisso@4015 203 _last_gc_worker_end_times_ms.set(worker_i, ms);
brutisso@3923 204 }
brutisso@3923 205
brutisso@3923 206 void record_clear_ct_time(double ms) {
brutisso@3923 207 _cur_clear_ct_time_ms = ms;
brutisso@3923 208 }
brutisso@3923 209
brutisso@3923 210 void record_par_time(double ms) {
brutisso@3923 211 _cur_collection_par_time_ms = ms;
brutisso@3923 212 }
brutisso@3923 213
brutisso@3923 214 void record_code_root_fixup_time(double ms) {
brutisso@3923 215 _cur_collection_code_root_fixup_time_ms = ms;
brutisso@3923 216 }
brutisso@3923 217
brutisso@3923 218 void record_ref_proc_time(double ms) {
brutisso@3923 219 _cur_ref_proc_time_ms = ms;
brutisso@3923 220 }
brutisso@3923 221
brutisso@3923 222 void record_ref_enq_time(double ms) {
brutisso@3923 223 _cur_ref_enq_time_ms = ms;
brutisso@3923 224 }
brutisso@3923 225
brutisso@3923 226 void record_root_region_scan_wait_time(double time_ms) {
brutisso@3923 227 _root_region_scan_wait_time_ms = time_ms;
brutisso@3923 228 }
brutisso@3923 229
brutisso@3923 230 void record_cc_clear_time_ms(double ms);
brutisso@3923 231
brutisso@3923 232 void record_young_free_cset_time_ms(double time_ms) {
brutisso@3923 233 _recorded_young_free_cset_time_ms = time_ms;
brutisso@3923 234 }
brutisso@3923 235
brutisso@3923 236 void record_non_young_free_cset_time_ms(double time_ms) {
brutisso@3923 237 _recorded_non_young_free_cset_time_ms = time_ms;
brutisso@3923 238 }
brutisso@4015 239
brutisso@4015 240 void record_young_cset_choice_time_ms(double time_ms) {
brutisso@4015 241 _recorded_young_cset_choice_time_ms = time_ms;
brutisso@4015 242 }
brutisso@4015 243
brutisso@4015 244 void record_non_young_cset_choice_time_ms(double time_ms) {
brutisso@4015 245 _recorded_non_young_cset_choice_time_ms = time_ms;
brutisso@4015 246 }
brutisso@4015 247
brutisso@4015 248 void record_cur_collection_start_sec(double time_ms) {
brutisso@4015 249 _cur_collection_start_sec = time_ms;
brutisso@4015 250 }
brutisso@4015 251
brutisso@4015 252 void record_verify_before_time_ms(double time_ms) {
brutisso@4015 253 _cur_verify_before_time_ms = time_ms;
brutisso@4015 254 }
brutisso@4015 255
brutisso@4015 256 void record_verify_after_time_ms(double time_ms) {
brutisso@4015 257 _cur_verify_after_time_ms = time_ms;
brutisso@4015 258 }
brutisso@4015 259
brutisso@4015 260 double accounted_time_ms();
brutisso@4015 261
brutisso@4015 262 double cur_collection_start_sec() {
brutisso@4015 263 return _cur_collection_start_sec;
brutisso@4015 264 }
brutisso@4015 265
brutisso@4015 266 double cur_collection_par_time_ms() {
brutisso@4015 267 return _cur_collection_par_time_ms;
brutisso@4015 268 }
brutisso@4015 269
brutisso@4015 270 double cur_clear_ct_time_ms() {
brutisso@4015 271 return _cur_clear_ct_time_ms;
brutisso@4015 272 }
brutisso@4015 273
brutisso@4015 274 double root_region_scan_wait_time_ms() {
brutisso@4015 275 return _root_region_scan_wait_time_ms;
brutisso@4015 276 }
brutisso@4015 277
brutisso@4015 278 double young_cset_choice_time_ms() {
brutisso@4015 279 return _recorded_young_cset_choice_time_ms;
brutisso@4015 280 }
brutisso@4015 281
brutisso@4015 282 double young_free_cset_time_ms() {
brutisso@4015 283 return _recorded_young_free_cset_time_ms;
brutisso@4015 284 }
brutisso@4015 285
brutisso@4015 286 double non_young_cset_choice_time_ms() {
brutisso@4015 287 return _recorded_non_young_cset_choice_time_ms;
brutisso@4015 288 }
brutisso@4015 289
brutisso@4015 290 double non_young_free_cset_time_ms() {
brutisso@4015 291 return _recorded_non_young_free_cset_time_ms;
brutisso@4015 292 }
brutisso@4015 293
brutisso@4015 294 double average_last_update_rs_time() {
brutisso@4015 295 return _last_update_rs_times_ms.average();
brutisso@4015 296 }
brutisso@4015 297
brutisso@4015 298 int sum_last_update_rs_processed_buffers() {
brutisso@4015 299 return _last_update_rs_processed_buffers.sum();
brutisso@4015 300 }
brutisso@4015 301
brutisso@4015 302 double average_last_scan_rs_time(){
brutisso@4015 303 return _last_scan_rs_times_ms.average();
brutisso@4015 304 }
brutisso@4015 305
brutisso@4015 306 double average_last_obj_copy_time() {
brutisso@4015 307 return _last_obj_copy_times_ms.average();
brutisso@4015 308 }
brutisso@4015 309
brutisso@4015 310 double average_last_termination_time() {
brutisso@4015 311 return _last_termination_times_ms.average();
brutisso@4015 312 }
brutisso@4015 313
brutisso@4015 314 double average_last_ext_root_scan_time() {
brutisso@4015 315 return _last_ext_root_scan_times_ms.average();
brutisso@4015 316 }
brutisso@4015 317
brutisso@4015 318 double average_last_satb_filtering_times_ms() {
brutisso@4015 319 return _last_satb_filtering_times_ms.average();
brutisso@4015 320 }
brutisso@3923 321 };
brutisso@3923 322
brutisso@3923 323 #endif // SHARE_VM_GC_IMPLEMENTATION_G1_G1GCPHASETIMESLOG_HPP

mercurial