ysr@777: /* xdono@1014: * Copyright 2001-2009 Sun Microsystems, Inc. All Rights Reserved. ysr@777: * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. ysr@777: * ysr@777: * This code is free software; you can redistribute it and/or modify it ysr@777: * under the terms of the GNU General Public License version 2 only, as ysr@777: * published by the Free Software Foundation. ysr@777: * ysr@777: * This code is distributed in the hope that it will be useful, but WITHOUT ysr@777: * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or ysr@777: * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License ysr@777: * version 2 for more details (a copy is included in the LICENSE file that ysr@777: * accompanied this code). ysr@777: * ysr@777: * You should have received a copy of the GNU General Public License version ysr@777: * 2 along with this work; if not, write to the Free Software Foundation, ysr@777: * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. ysr@777: * ysr@777: * Please contact Sun Microsystems, Inc., 4150 Network Circle, Santa Clara, ysr@777: * CA 95054 USA or visit www.sun.com if you need additional information or ysr@777: * have any questions. ysr@777: * ysr@777: */ ysr@777: ysr@777: #include "incls/_precompiled.incl" ysr@777: #include "incls/_g1CollectorPolicy.cpp.incl" ysr@777: ysr@777: #define PREDICTIONS_VERBOSE 0 ysr@777: ysr@777: // ysr@777: ysr@777: // Different defaults for different number of GC threads ysr@777: // They were chosen by running GCOld and SPECjbb on debris with different ysr@777: // numbers of GC threads and choosing them based on the results ysr@777: ysr@777: // all the same ysr@777: static double rs_length_diff_defaults[] = { ysr@777: 0.0, 0.0, 0.0, 0.0, 0.0, 0.0, 0.0, 0.0 ysr@777: }; ysr@777: ysr@777: static double cost_per_card_ms_defaults[] = { ysr@777: 0.01, 0.005, 0.005, 0.003, 0.003, 0.002, 0.002, 0.0015 ysr@777: }; ysr@777: ysr@777: static double cost_per_scan_only_region_ms_defaults[] = { ysr@777: 1.0, 1.0, 1.0, 1.0, 1.0, 1.0, 1.0, 1.0 ysr@777: }; ysr@777: ysr@777: // all the same ysr@777: static double fully_young_cards_per_entry_ratio_defaults[] = { ysr@777: 1.0, 1.0, 1.0, 1.0, 1.0, 1.0, 1.0, 1.0 ysr@777: }; ysr@777: ysr@777: static double cost_per_entry_ms_defaults[] = { ysr@777: 0.015, 0.01, 0.01, 0.008, 0.008, 0.0055, 0.0055, 0.005 ysr@777: }; ysr@777: ysr@777: static double cost_per_byte_ms_defaults[] = { ysr@777: 0.00006, 0.00003, 0.00003, 0.000015, 0.000015, 0.00001, 0.00001, 0.000009 ysr@777: }; ysr@777: ysr@777: // these should be pretty consistent ysr@777: static double constant_other_time_ms_defaults[] = { ysr@777: 5.0, 5.0, 5.0, 5.0, 5.0, 5.0, 5.0, 5.0 ysr@777: }; ysr@777: ysr@777: ysr@777: static double young_other_cost_per_region_ms_defaults[] = { ysr@777: 0.3, 0.2, 0.2, 0.15, 0.15, 0.12, 0.12, 0.1 ysr@777: }; ysr@777: ysr@777: static double non_young_other_cost_per_region_ms_defaults[] = { ysr@777: 1.0, 0.7, 0.7, 0.5, 0.5, 0.42, 0.42, 0.30 ysr@777: }; ysr@777: ysr@777: // ysr@777: ysr@777: G1CollectorPolicy::G1CollectorPolicy() : ysr@777: _parallel_gc_threads((ParallelGCThreads > 0) ? ParallelGCThreads : 1), ysr@777: _n_pauses(0), ysr@777: _recent_CH_strong_roots_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_G1_strong_roots_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_evac_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_pause_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_rs_sizes(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_gc_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _all_pause_times_ms(new NumberSeq()), ysr@777: _stop_world_start(0.0), ysr@777: _all_stop_world_times_ms(new NumberSeq()), ysr@777: _all_yield_times_ms(new NumberSeq()), ysr@777: ysr@777: _all_mod_union_times_ms(new NumberSeq()), ysr@777: ysr@777: _non_pop_summary(new NonPopSummary()), ysr@777: _pop_summary(new PopSummary()), ysr@777: _non_pop_abandoned_summary(new NonPopAbandonedSummary()), ysr@777: _pop_abandoned_summary(new PopAbandonedSummary()), ysr@777: ysr@777: _cur_clear_ct_time_ms(0.0), ysr@777: ysr@777: _region_num_young(0), ysr@777: _region_num_tenured(0), ysr@777: _prev_region_num_young(0), ysr@777: _prev_region_num_tenured(0), ysr@777: ysr@777: _aux_num(10), ysr@777: _all_aux_times_ms(new NumberSeq[_aux_num]), ysr@777: _cur_aux_start_times_ms(new double[_aux_num]), ysr@777: _cur_aux_times_ms(new double[_aux_num]), ysr@777: _cur_aux_times_set(new bool[_aux_num]), ysr@777: ysr@777: _pop_compute_rc_start(0.0), ysr@777: _pop_evac_start(0.0), ysr@777: ysr@777: _concurrent_mark_init_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _concurrent_mark_remark_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _concurrent_mark_cleanup_times_ms(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: ysr@777: // ysr@777: ysr@777: _alloc_rate_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _prev_collection_pause_end_ms(0.0), ysr@777: _pending_card_diff_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _rs_length_diff_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_card_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_scan_only_region_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _fully_young_cards_per_entry_ratio_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _partially_young_cards_per_entry_ratio_seq( ysr@777: new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_entry_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _partially_young_cost_per_entry_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_byte_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_byte_ms_during_cm_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _cost_per_scan_only_region_ms_during_cm_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _constant_other_time_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _young_other_cost_per_region_ms_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _non_young_other_cost_per_region_ms_seq( ysr@777: new TruncatedSeq(TruncatedSeqLength)), ysr@777: ysr@777: _pending_cards_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _scanned_cards_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _rs_lengths_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: ysr@777: _pause_time_target_ms((double) G1MaxPauseTimeMS), ysr@777: ysr@777: // ysr@777: ysr@777: _in_young_gc_mode(false), ysr@777: _full_young_gcs(true), ysr@777: _full_young_pause_num(0), ysr@777: _partial_young_pause_num(0), ysr@777: ysr@777: _during_marking(false), ysr@777: _in_marking_window(false), ysr@777: _in_marking_window_im(false), ysr@777: ysr@777: _known_garbage_ratio(0.0), ysr@777: _known_garbage_bytes(0), ysr@777: ysr@777: _young_gc_eff_seq(new TruncatedSeq(TruncatedSeqLength)), ysr@777: _target_pause_time_ms(-1.0), ysr@777: ysr@777: _recent_prev_end_times_for_all_gcs_sec(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: ysr@777: _recent_CS_bytes_used_before(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: _recent_CS_bytes_surviving(new TruncatedSeq(NumPrevPausesForHeuristics)), ysr@777: ysr@777: _recent_avg_pause_time_ratio(0.0), ysr@777: _num_markings(0), ysr@777: _n_marks(0), ysr@777: _n_pauses_at_mark_end(0), ysr@777: ysr@777: _all_full_gc_times_ms(new NumberSeq()), ysr@777: ysr@777: _conc_refine_enabled(0), ysr@777: _conc_refine_zero_traversals(0), ysr@777: _conc_refine_max_traversals(0), ysr@777: _conc_refine_current_delta(G1ConcRefineInitialDelta), ysr@777: ysr@777: // G1PausesBtwnConcMark defaults to -1 ysr@777: // so the hack is to do the cast QQQ FIXME ysr@777: _pauses_btwn_concurrent_mark((size_t)G1PausesBtwnConcMark), ysr@777: _n_marks_since_last_pause(0), ysr@777: _conc_mark_initiated(false), ysr@777: _should_initiate_conc_mark(false), ysr@777: _should_revert_to_full_young_gcs(false), ysr@777: _last_full_young_gc(false), ysr@777: ysr@777: _prev_collection_pause_used_at_end_bytes(0), ysr@777: ysr@777: _collection_set(NULL), ysr@777: #ifdef _MSC_VER // the use of 'this' below gets a warning, make it go away ysr@777: #pragma warning( disable:4355 ) // 'this' : used in base member initializer list ysr@777: #endif // _MSC_VER ysr@777: ysr@777: _short_lived_surv_rate_group(new SurvRateGroup(this, "Short Lived", ysr@777: G1YoungSurvRateNumRegionsSummary)), ysr@777: _survivor_surv_rate_group(new SurvRateGroup(this, "Survivor", apetrusenko@980: G1YoungSurvRateNumRegionsSummary)), ysr@777: // add here any more surv rate groups apetrusenko@980: _recorded_survivor_regions(0), apetrusenko@980: _recorded_survivor_head(NULL), apetrusenko@980: _recorded_survivor_tail(NULL), apetrusenko@980: _survivors_age_table(true) apetrusenko@980: ysr@777: { ysr@777: _recent_prev_end_times_for_all_gcs_sec->add(os::elapsedTime()); ysr@777: _prev_collection_pause_end_ms = os::elapsedTime() * 1000.0; ysr@777: ysr@777: _par_last_ext_root_scan_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_mark_stack_scan_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_scan_only_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_scan_only_regions_scanned = new double[_parallel_gc_threads]; ysr@777: ysr@777: _par_last_update_rs_start_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_update_rs_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_update_rs_processed_buffers = new double[_parallel_gc_threads]; ysr@777: ysr@777: _par_last_scan_rs_start_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_scan_rs_times_ms = new double[_parallel_gc_threads]; ysr@777: _par_last_scan_new_refs_times_ms = new double[_parallel_gc_threads]; ysr@777: ysr@777: _par_last_obj_copy_times_ms = new double[_parallel_gc_threads]; ysr@777: ysr@777: _par_last_termination_times_ms = new double[_parallel_gc_threads]; ysr@777: ysr@777: // we store the data from the first pass during popularity pauses ysr@777: _pop_par_last_update_rs_start_times_ms = new double[_parallel_gc_threads]; ysr@777: _pop_par_last_update_rs_times_ms = new double[_parallel_gc_threads]; ysr@777: _pop_par_last_update_rs_processed_buffers = new double[_parallel_gc_threads]; ysr@777: ysr@777: _pop_par_last_scan_rs_start_times_ms = new double[_parallel_gc_threads]; ysr@777: _pop_par_last_scan_rs_times_ms = new double[_parallel_gc_threads]; ysr@777: ysr@777: _pop_par_last_closure_app_times_ms = new double[_parallel_gc_threads]; ysr@777: ysr@777: // start conservatively ysr@777: _expensive_region_limit_ms = 0.5 * (double) G1MaxPauseTimeMS; ysr@777: ysr@777: // ysr@777: ysr@777: int index; ysr@777: if (ParallelGCThreads == 0) ysr@777: index = 0; ysr@777: else if (ParallelGCThreads > 8) ysr@777: index = 7; ysr@777: else ysr@777: index = ParallelGCThreads - 1; ysr@777: ysr@777: _pending_card_diff_seq->add(0.0); ysr@777: _rs_length_diff_seq->add(rs_length_diff_defaults[index]); ysr@777: _cost_per_card_ms_seq->add(cost_per_card_ms_defaults[index]); ysr@777: _cost_per_scan_only_region_ms_seq->add( ysr@777: cost_per_scan_only_region_ms_defaults[index]); ysr@777: _fully_young_cards_per_entry_ratio_seq->add( ysr@777: fully_young_cards_per_entry_ratio_defaults[index]); ysr@777: _cost_per_entry_ms_seq->add(cost_per_entry_ms_defaults[index]); ysr@777: _cost_per_byte_ms_seq->add(cost_per_byte_ms_defaults[index]); ysr@777: _constant_other_time_ms_seq->add(constant_other_time_ms_defaults[index]); ysr@777: _young_other_cost_per_region_ms_seq->add( ysr@777: young_other_cost_per_region_ms_defaults[index]); ysr@777: _non_young_other_cost_per_region_ms_seq->add( ysr@777: non_young_other_cost_per_region_ms_defaults[index]); ysr@777: ysr@777: // ysr@777: ysr@777: double time_slice = (double) G1TimeSliceMS / 1000.0; ysr@777: double max_gc_time = (double) G1MaxPauseTimeMS / 1000.0; ysr@777: guarantee(max_gc_time < time_slice, ysr@777: "Max GC time should not be greater than the time slice"); ysr@777: _mmu_tracker = new G1MMUTrackerQueue(time_slice, max_gc_time); ysr@777: _sigma = (double) G1ConfidencePerc / 100.0; ysr@777: ysr@777: // start conservatively (around 50ms is about right) ysr@777: _concurrent_mark_init_times_ms->add(0.05); ysr@777: _concurrent_mark_remark_times_ms->add(0.05); ysr@777: _concurrent_mark_cleanup_times_ms->add(0.20); ysr@777: _tenuring_threshold = MaxTenuringThreshold; ysr@777: apetrusenko@980: if (G1UseSurvivorSpace) { apetrusenko@980: // if G1FixedSurvivorSpaceSize is 0 which means the size is not apetrusenko@980: // fixed, then _max_survivor_regions will be calculated at apetrusenko@982: // calculate_young_list_target_config during initialization apetrusenko@980: _max_survivor_regions = G1FixedSurvivorSpaceSize / HeapRegion::GrainBytes; apetrusenko@980: } else { apetrusenko@980: _max_survivor_regions = 0; apetrusenko@980: } apetrusenko@980: ysr@777: initialize_all(); ysr@777: } ysr@777: ysr@777: // Increment "i", mod "len" ysr@777: static void inc_mod(int& i, int len) { ysr@777: i++; if (i == len) i = 0; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::initialize_flags() { ysr@777: set_min_alignment(HeapRegion::GrainBytes); ysr@777: set_max_alignment(GenRemSet::max_alignment_constraint(rem_set_name())); apetrusenko@982: if (SurvivorRatio < 1) { apetrusenko@982: vm_exit_during_initialization("Invalid survivor ratio specified"); apetrusenko@982: } ysr@777: CollectorPolicy::initialize_flags(); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::init() { ysr@777: // Set aside an initial future to_space. ysr@777: _g1 = G1CollectedHeap::heap(); ysr@777: size_t regions = Universe::heap()->capacity() / HeapRegion::GrainBytes; ysr@777: ysr@777: assert(Heap_lock->owned_by_self(), "Locking discipline."); ysr@777: ysr@777: if (G1SteadyStateUsed < 50) { ysr@777: vm_exit_during_initialization("G1SteadyStateUsed must be at least 50%."); ysr@777: } ysr@777: if (UseConcMarkSweepGC) { ysr@777: vm_exit_during_initialization("-XX:+UseG1GC is incompatible with " ysr@777: "-XX:+UseConcMarkSweepGC."); ysr@777: } ysr@777: apetrusenko@980: initialize_gc_policy_counters(); apetrusenko@980: ysr@777: if (G1Gen) { ysr@777: _in_young_gc_mode = true; ysr@777: ysr@777: if (G1YoungGenSize == 0) { ysr@777: set_adaptive_young_list_length(true); ysr@777: _young_list_fixed_length = 0; ysr@777: } else { ysr@777: set_adaptive_young_list_length(false); ysr@777: _young_list_fixed_length = (G1YoungGenSize / HeapRegion::GrainBytes); ysr@777: } ysr@777: _free_regions_at_end_of_collection = _g1->free_regions(); ysr@777: _scan_only_regions_at_end_of_collection = 0; ysr@777: calculate_young_list_min_length(); ysr@777: guarantee( _young_list_min_length == 0, "invariant, not enough info" ); ysr@777: calculate_young_list_target_config(); ysr@777: } else { ysr@777: _young_list_fixed_length = 0; ysr@777: _in_young_gc_mode = false; ysr@777: } ysr@777: } ysr@777: apetrusenko@980: // Create the jstat counters for the policy. apetrusenko@980: void G1CollectorPolicy::initialize_gc_policy_counters() apetrusenko@980: { apetrusenko@980: _gc_policy_counters = new GCPolicyCounters("GarbageFirst", 1, 2 + G1Gen); apetrusenko@980: } apetrusenko@980: ysr@777: void G1CollectorPolicy::calculate_young_list_min_length() { ysr@777: _young_list_min_length = 0; ysr@777: ysr@777: if (!adaptive_young_list_length()) ysr@777: return; ysr@777: ysr@777: if (_alloc_rate_ms_seq->num() > 3) { ysr@777: double now_sec = os::elapsedTime(); ysr@777: double when_ms = _mmu_tracker->when_max_gc_sec(now_sec) * 1000.0; ysr@777: double alloc_rate_ms = predict_alloc_rate_ms(); ysr@777: int min_regions = (int) ceil(alloc_rate_ms * when_ms); ysr@777: int current_region_num = (int) _g1->young_list_length(); ysr@777: _young_list_min_length = min_regions + current_region_num; ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::calculate_young_list_target_config() { ysr@777: if (adaptive_young_list_length()) { ysr@777: size_t rs_lengths = (size_t) get_new_prediction(_rs_lengths_seq); ysr@777: calculate_young_list_target_config(rs_lengths); ysr@777: } else { ysr@777: if (full_young_gcs()) ysr@777: _young_list_target_length = _young_list_fixed_length; ysr@777: else ysr@777: _young_list_target_length = _young_list_fixed_length / 2; ysr@777: _young_list_target_length = MAX2(_young_list_target_length, (size_t)1); ysr@777: size_t so_length = calculate_optimal_so_length(_young_list_target_length); ysr@777: guarantee( so_length < _young_list_target_length, "invariant" ); ysr@777: _young_list_so_prefix_length = so_length; ysr@777: } apetrusenko@980: calculate_survivors_policy(); ysr@777: } ysr@777: ysr@777: // This method calculate the optimal scan-only set for a fixed young ysr@777: // gen size. I couldn't work out how to reuse the more elaborate one, ysr@777: // i.e. calculate_young_list_target_config(rs_length), as the loops are ysr@777: // fundamentally different (the other one finds a config for different ysr@777: // S-O lengths, whereas here we need to do the opposite). ysr@777: size_t G1CollectorPolicy::calculate_optimal_so_length( ysr@777: size_t young_list_length) { ysr@777: if (!G1UseScanOnlyPrefix) ysr@777: return 0; ysr@777: ysr@777: if (_all_pause_times_ms->num() < 3) { ysr@777: // we won't use a scan-only set at the beginning to allow the rest ysr@777: // of the predictors to warm up ysr@777: return 0; ysr@777: } ysr@777: ysr@777: if (_cost_per_scan_only_region_ms_seq->num() < 3) { ysr@777: // then, we'll only set the S-O set to 1 for a little bit of time, ysr@777: // to get enough information on the scanning cost ysr@777: return 1; ysr@777: } ysr@777: ysr@777: size_t pending_cards = (size_t) get_new_prediction(_pending_cards_seq); ysr@777: size_t rs_lengths = (size_t) get_new_prediction(_rs_lengths_seq); ysr@777: size_t adj_rs_lengths = rs_lengths + predict_rs_length_diff(); ysr@777: size_t scanned_cards; ysr@777: if (full_young_gcs()) ysr@777: scanned_cards = predict_young_card_num(adj_rs_lengths); ysr@777: else ysr@777: scanned_cards = predict_non_young_card_num(adj_rs_lengths); ysr@777: double base_time_ms = predict_base_elapsed_time_ms(pending_cards, ysr@777: scanned_cards); ysr@777: ysr@777: size_t so_length = 0; ysr@777: double max_gc_eff = 0.0; ysr@777: for (size_t i = 0; i < young_list_length; ++i) { ysr@777: double gc_eff = 0.0; ysr@777: double pause_time_ms = 0.0; ysr@777: predict_gc_eff(young_list_length, i, base_time_ms, ysr@777: &gc_eff, &pause_time_ms); ysr@777: if (gc_eff > max_gc_eff) { ysr@777: max_gc_eff = gc_eff; ysr@777: so_length = i; ysr@777: } ysr@777: } ysr@777: ysr@777: // set it to 95% of the optimal to make sure we sample the "area" ysr@777: // around the optimal length to get up-to-date survival rate data ysr@777: return so_length * 950 / 1000; ysr@777: } ysr@777: ysr@777: // This is a really cool piece of code! It finds the best ysr@777: // target configuration (young length / scan-only prefix length) so ysr@777: // that GC efficiency is maximized and that we also meet a pause ysr@777: // time. It's a triple nested loop. These loops are explained below ysr@777: // from the inside-out :-) ysr@777: // ysr@777: // (a) The innermost loop will try to find the optimal young length ysr@777: // for a fixed S-O length. It uses a binary search to speed up the ysr@777: // process. We assume that, for a fixed S-O length, as we add more ysr@777: // young regions to the CSet, the GC efficiency will only go up (I'll ysr@777: // skip the proof). So, using a binary search to optimize this process ysr@777: // makes perfect sense. ysr@777: // ysr@777: // (b) The middle loop will fix the S-O length before calling the ysr@777: // innermost one. It will vary it between two parameters, increasing ysr@777: // it by a given increment. ysr@777: // ysr@777: // (c) The outermost loop will call the middle loop three times. ysr@777: // (1) The first time it will explore all possible S-O length values ysr@777: // from 0 to as large as it can get, using a coarse increment (to ysr@777: // quickly "home in" to where the optimal seems to be). ysr@777: // (2) The second time it will explore the values around the optimal ysr@777: // that was found by the first iteration using a fine increment. ysr@777: // (3) Once the optimal config has been determined by the second ysr@777: // iteration, we'll redo the calculation, but setting the S-O length ysr@777: // to 95% of the optimal to make sure we sample the "area" ysr@777: // around the optimal length to get up-to-date survival rate data ysr@777: // ysr@777: // Termination conditions for the iterations are several: the pause ysr@777: // time is over the limit, we do not have enough to-space, etc. ysr@777: ysr@777: void G1CollectorPolicy::calculate_young_list_target_config(size_t rs_lengths) { ysr@777: guarantee( adaptive_young_list_length(), "pre-condition" ); ysr@777: ysr@777: double start_time_sec = os::elapsedTime(); ysr@777: size_t min_reserve_perc = MAX2((size_t)2, (size_t)G1MinReservePerc); ysr@777: min_reserve_perc = MIN2((size_t) 50, min_reserve_perc); ysr@777: size_t reserve_regions = ysr@777: (size_t) ((double) min_reserve_perc * (double) _g1->n_regions() / 100.0); ysr@777: ysr@777: if (full_young_gcs() && _free_regions_at_end_of_collection > 0) { ysr@777: // we are in fully-young mode and there are free regions in the heap ysr@777: apetrusenko@980: double survivor_regions_evac_time = apetrusenko@980: predict_survivor_regions_evac_time(); apetrusenko@980: ysr@777: size_t min_so_length = 0; ysr@777: size_t max_so_length = 0; ysr@777: ysr@777: if (G1UseScanOnlyPrefix) { ysr@777: if (_all_pause_times_ms->num() < 3) { ysr@777: // we won't use a scan-only set at the beginning to allow the rest ysr@777: // of the predictors to warm up ysr@777: min_so_length = 0; ysr@777: max_so_length = 0; ysr@777: } else if (_cost_per_scan_only_region_ms_seq->num() < 3) { ysr@777: // then, we'll only set the S-O set to 1 for a little bit of time, ysr@777: // to get enough information on the scanning cost ysr@777: min_so_length = 1; ysr@777: max_so_length = 1; ysr@777: } else if (_in_marking_window || _last_full_young_gc) { ysr@777: // no S-O prefix during a marking phase either, as at the end ysr@777: // of the marking phase we'll have to use a very small young ysr@777: // length target to fill up the rest of the CSet with ysr@777: // non-young regions and, if we have lots of scan-only regions ysr@777: // left-over, we will not be able to add any more non-young ysr@777: // regions. ysr@777: min_so_length = 0; ysr@777: max_so_length = 0; ysr@777: } else { ysr@777: // this is the common case; we'll never reach the maximum, we ysr@777: // one of the end conditions will fire well before that ysr@777: // (hopefully!) ysr@777: min_so_length = 0; ysr@777: max_so_length = _free_regions_at_end_of_collection - 1; ysr@777: } ysr@777: } else { ysr@777: // no S-O prefix, as the switch is not set, but we still need to ysr@777: // do one iteration to calculate the best young target that ysr@777: // meets the pause time; this way we reuse the same code instead ysr@777: // of replicating it ysr@777: min_so_length = 0; ysr@777: max_so_length = 0; ysr@777: } ysr@777: ysr@777: double target_pause_time_ms = _mmu_tracker->max_gc_time() * 1000.0; ysr@777: size_t pending_cards = (size_t) get_new_prediction(_pending_cards_seq); ysr@777: size_t adj_rs_lengths = rs_lengths + predict_rs_length_diff(); ysr@777: size_t scanned_cards; ysr@777: if (full_young_gcs()) ysr@777: scanned_cards = predict_young_card_num(adj_rs_lengths); ysr@777: else ysr@777: scanned_cards = predict_non_young_card_num(adj_rs_lengths); ysr@777: // calculate this once, so that we don't have to recalculate it in ysr@777: // the innermost loop apetrusenko@980: double base_time_ms = predict_base_elapsed_time_ms(pending_cards, scanned_cards) apetrusenko@980: + survivor_regions_evac_time; ysr@777: // the result ysr@777: size_t final_young_length = 0; ysr@777: size_t final_so_length = 0; ysr@777: double final_gc_eff = 0.0; ysr@777: // we'll also keep track of how many times we go into the inner loop ysr@777: // this is for profiling reasons ysr@777: size_t calculations = 0; ysr@777: ysr@777: // this determines which of the three iterations the outer loop is in ysr@777: typedef enum { ysr@777: pass_type_coarse, ysr@777: pass_type_fine, ysr@777: pass_type_final ysr@777: } pass_type_t; ysr@777: ysr@777: // range of the outer loop's iteration ysr@777: size_t from_so_length = min_so_length; ysr@777: size_t to_so_length = max_so_length; ysr@777: guarantee( from_so_length <= to_so_length, "invariant" ); ysr@777: ysr@777: // this will keep the S-O length that's found by the second ysr@777: // iteration of the outer loop; we'll keep it just in case the third ysr@777: // iteration fails to find something ysr@777: size_t fine_so_length = 0; ysr@777: ysr@777: // the increment step for the coarse (first) iteration ysr@777: size_t so_coarse_increments = 5; ysr@777: ysr@777: // the common case, we'll start with the coarse iteration ysr@777: pass_type_t pass = pass_type_coarse; ysr@777: size_t so_length_incr = so_coarse_increments; ysr@777: ysr@777: if (from_so_length == to_so_length) { ysr@777: // not point in doing the coarse iteration, we'll go directly into ysr@777: // the fine one (we essentially trying to find the optimal young ysr@777: // length for a fixed S-O length). ysr@777: so_length_incr = 1; ysr@777: pass = pass_type_final; ysr@777: } else if (to_so_length - from_so_length < 3 * so_coarse_increments) { ysr@777: // again, the range is too short so no point in foind the coarse ysr@777: // iteration either ysr@777: so_length_incr = 1; ysr@777: pass = pass_type_fine; ysr@777: } ysr@777: ysr@777: bool done = false; ysr@777: // this is the outermost loop ysr@777: while (!done) { apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr("searching between " SIZE_FORMAT " and " SIZE_FORMAT ysr@777: ", incr " SIZE_FORMAT ", pass %s", ysr@777: from_so_length, to_so_length, so_length_incr, ysr@777: (pass == pass_type_coarse) ? "coarse" : ysr@777: (pass == pass_type_fine) ? "fine" : "final"); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: size_t so_length = from_so_length; ysr@777: size_t init_free_regions = ysr@777: MAX2((size_t)0, ysr@777: _free_regions_at_end_of_collection + ysr@777: _scan_only_regions_at_end_of_collection - reserve_regions); ysr@777: ysr@777: // this determines whether a configuration was found ysr@777: bool gc_eff_set = false; ysr@777: // this is the middle loop ysr@777: while (so_length <= to_so_length) { ysr@777: // base time, which excludes region-related time; again we ysr@777: // calculate it once to avoid recalculating it in the ysr@777: // innermost loop ysr@777: double base_time_with_so_ms = ysr@777: base_time_ms + predict_scan_only_time_ms(so_length); ysr@777: // it's already over the pause target, go around ysr@777: if (base_time_with_so_ms > target_pause_time_ms) ysr@777: break; ysr@777: ysr@777: size_t starting_young_length = so_length+1; ysr@777: ysr@777: // we make sure that the short young length that makes sense ysr@777: // (one more than the S-O length) is feasible ysr@777: size_t min_young_length = starting_young_length; ysr@777: double min_gc_eff; ysr@777: bool min_ok; ysr@777: ++calculations; ysr@777: min_ok = predict_gc_eff(min_young_length, so_length, ysr@777: base_time_with_so_ms, ysr@777: init_free_regions, target_pause_time_ms, ysr@777: &min_gc_eff); ysr@777: ysr@777: if (min_ok) { ysr@777: // the shortest young length is indeed feasible; we'll know ysr@777: // set up the max young length and we'll do a binary search ysr@777: // between min_young_length and max_young_length ysr@777: size_t max_young_length = _free_regions_at_end_of_collection - 1; ysr@777: double max_gc_eff = 0.0; ysr@777: bool max_ok = false; ysr@777: ysr@777: // the innermost loop! (finally!) ysr@777: while (max_young_length > min_young_length) { ysr@777: // we'll make sure that min_young_length is always at a ysr@777: // feasible config ysr@777: guarantee( min_ok, "invariant" ); ysr@777: ysr@777: ++calculations; ysr@777: max_ok = predict_gc_eff(max_young_length, so_length, ysr@777: base_time_with_so_ms, ysr@777: init_free_regions, target_pause_time_ms, ysr@777: &max_gc_eff); ysr@777: ysr@777: size_t diff = (max_young_length - min_young_length) / 2; ysr@777: if (max_ok) { ysr@777: min_young_length = max_young_length; ysr@777: min_gc_eff = max_gc_eff; ysr@777: min_ok = true; ysr@777: } ysr@777: max_young_length = min_young_length + diff; ysr@777: } ysr@777: ysr@777: // the innermost loop found a config ysr@777: guarantee( min_ok, "invariant" ); ysr@777: if (min_gc_eff > final_gc_eff) { ysr@777: // it's the best config so far, so we'll keep it ysr@777: final_gc_eff = min_gc_eff; ysr@777: final_young_length = min_young_length; ysr@777: final_so_length = so_length; ysr@777: gc_eff_set = true; ysr@777: } ysr@777: } ysr@777: ysr@777: // incremental the fixed S-O length and go around ysr@777: so_length += so_length_incr; ysr@777: } ysr@777: ysr@777: // this is the end of the outermost loop and we need to decide ysr@777: // what to do during the next iteration ysr@777: if (pass == pass_type_coarse) { ysr@777: // we just did the coarse pass (first iteration) ysr@777: ysr@777: if (!gc_eff_set) ysr@777: // we didn't find a feasible config so we'll just bail out; of ysr@777: // course, it might be the case that we missed it; but I'd say ysr@777: // it's a bit unlikely ysr@777: done = true; ysr@777: else { ysr@777: // We did find a feasible config with optimal GC eff during ysr@777: // the first pass. So the second pass we'll only consider the ysr@777: // S-O lengths around that config with a fine increment. ysr@777: ysr@777: guarantee( so_length_incr == so_coarse_increments, "invariant" ); ysr@777: guarantee( final_so_length >= min_so_length, "invariant" ); ysr@777: apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr(" coarse pass: SO length " SIZE_FORMAT, ysr@777: final_so_length); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: from_so_length = ysr@777: (final_so_length - min_so_length > so_coarse_increments) ? ysr@777: final_so_length - so_coarse_increments + 1 : min_so_length; ysr@777: to_so_length = ysr@777: (max_so_length - final_so_length > so_coarse_increments) ? ysr@777: final_so_length + so_coarse_increments - 1 : max_so_length; ysr@777: ysr@777: pass = pass_type_fine; ysr@777: so_length_incr = 1; ysr@777: } ysr@777: } else if (pass == pass_type_fine) { ysr@777: // we just finished the second pass ysr@777: ysr@777: if (!gc_eff_set) { ysr@777: // we didn't find a feasible config (yes, it's possible; ysr@777: // notice that, sometimes, we go directly into the fine ysr@777: // iteration and skip the coarse one) so we bail out ysr@777: done = true; ysr@777: } else { ysr@777: // We did find a feasible config with optimal GC eff ysr@777: guarantee( so_length_incr == 1, "invariant" ); ysr@777: ysr@777: if (final_so_length == 0) { ysr@777: // The config is of an empty S-O set, so we'll just bail out ysr@777: done = true; ysr@777: } else { ysr@777: // we'll go around once more, setting the S-O length to 95% ysr@777: // of the optimal ysr@777: size_t new_so_length = 950 * final_so_length / 1000; ysr@777: apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr(" fine pass: SO length " SIZE_FORMAT ysr@777: ", setting it to " SIZE_FORMAT, ysr@777: final_so_length, new_so_length); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: from_so_length = new_so_length; ysr@777: to_so_length = new_so_length; ysr@777: fine_so_length = final_so_length; ysr@777: ysr@777: pass = pass_type_final; ysr@777: } ysr@777: } ysr@777: } else if (pass == pass_type_final) { ysr@777: // we just finished the final (third) pass ysr@777: ysr@777: if (!gc_eff_set) ysr@777: // we didn't find a feasible config, so we'll just use the one ysr@777: // we found during the second pass, which we saved ysr@777: final_so_length = fine_so_length; ysr@777: ysr@777: // and we're done! ysr@777: done = true; ysr@777: } else { ysr@777: guarantee( false, "should never reach here" ); ysr@777: } ysr@777: ysr@777: // we now go around the outermost loop ysr@777: } ysr@777: ysr@777: // we should have at least one region in the target young length apetrusenko@980: _young_list_target_length = apetrusenko@980: MAX2((size_t) 1, final_young_length + _recorded_survivor_regions); ysr@777: if (final_so_length >= final_young_length) ysr@777: // and we need to ensure that the S-O length is not greater than ysr@777: // the target young length (this is being a bit careful) ysr@777: final_so_length = 0; ysr@777: _young_list_so_prefix_length = final_so_length; ysr@777: guarantee( !_in_marking_window || !_last_full_young_gc || ysr@777: _young_list_so_prefix_length == 0, "invariant" ); ysr@777: ysr@777: // let's keep an eye of how long we spend on this calculation ysr@777: // right now, I assume that we'll print it when we need it; we ysr@777: // should really adde it to the breakdown of a pause ysr@777: double end_time_sec = os::elapsedTime(); ysr@777: double elapsed_time_ms = (end_time_sec - start_time_sec) * 1000.0; ysr@777: apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr("target = %1.1lf ms, young = " SIZE_FORMAT ysr@777: ", SO = " SIZE_FORMAT ", " ysr@777: "elapsed %1.2lf ms, calcs: " SIZE_FORMAT " (%s%s) " ysr@777: SIZE_FORMAT SIZE_FORMAT, ysr@777: target_pause_time_ms, ysr@777: _young_list_target_length - _young_list_so_prefix_length, ysr@777: _young_list_so_prefix_length, ysr@777: elapsed_time_ms, ysr@777: calculations, ysr@777: full_young_gcs() ? "full" : "partial", ysr@777: should_initiate_conc_mark() ? " i-m" : "", apetrusenko@980: _in_marking_window, apetrusenko@980: _in_marking_window_im); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: if (_young_list_target_length < _young_list_min_length) { ysr@777: // bummer; this means that, if we do a pause when the optimal ysr@777: // config dictates, we'll violate the pause spacing target (the ysr@777: // min length was calculate based on the application's current ysr@777: // alloc rate); ysr@777: ysr@777: // so, we have to bite the bullet, and allocate the minimum ysr@777: // number. We'll violate our target, but we just can't meet it. ysr@777: ysr@777: size_t so_length = 0; ysr@777: // a note further up explains why we do not want an S-O length ysr@777: // during marking ysr@777: if (!_in_marking_window && !_last_full_young_gc) ysr@777: // but we can still try to see whether we can find an optimal ysr@777: // S-O length ysr@777: so_length = calculate_optimal_so_length(_young_list_min_length); ysr@777: apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr("adjusted target length from " ysr@777: SIZE_FORMAT " to " SIZE_FORMAT ysr@777: ", SO " SIZE_FORMAT, ysr@777: _young_list_target_length, _young_list_min_length, ysr@777: so_length); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: _young_list_target_length = ysr@777: MAX2(_young_list_min_length, (size_t)1); ysr@777: _young_list_so_prefix_length = so_length; ysr@777: } ysr@777: } else { ysr@777: // we are in a partially-young mode or we've run out of regions (due ysr@777: // to evacuation failure) ysr@777: apetrusenko@980: #ifdef TRACE_CALC_YOUNG_CONFIG ysr@777: // leave this in for debugging, just in case ysr@777: gclog_or_tty->print_cr("(partial) setting target to " SIZE_FORMAT ysr@777: ", SO " SIZE_FORMAT, ysr@777: _young_list_min_length, 0); apetrusenko@980: #endif // TRACE_CALC_YOUNG_CONFIG ysr@777: ysr@777: // we'll do the pause as soon as possible and with no S-O prefix ysr@777: // (see above for the reasons behind the latter) ysr@777: _young_list_target_length = ysr@777: MAX2(_young_list_min_length, (size_t) 1); ysr@777: _young_list_so_prefix_length = 0; ysr@777: } ysr@777: ysr@777: _rs_lengths_prediction = rs_lengths; ysr@777: } ysr@777: ysr@777: // This is used by: calculate_optimal_so_length(length). It returns ysr@777: // the GC eff and predicted pause time for a particular config ysr@777: void ysr@777: G1CollectorPolicy::predict_gc_eff(size_t young_length, ysr@777: size_t so_length, ysr@777: double base_time_ms, ysr@777: double* ret_gc_eff, ysr@777: double* ret_pause_time_ms) { ysr@777: double so_time_ms = predict_scan_only_time_ms(so_length); ysr@777: double accum_surv_rate_adj = 0.0; ysr@777: if (so_length > 0) ysr@777: accum_surv_rate_adj = accum_yg_surv_rate_pred((int)(so_length - 1)); ysr@777: double accum_surv_rate = ysr@777: accum_yg_surv_rate_pred((int)(young_length - 1)) - accum_surv_rate_adj; ysr@777: size_t bytes_to_copy = ysr@777: (size_t) (accum_surv_rate * (double) HeapRegion::GrainBytes); ysr@777: double copy_time_ms = predict_object_copy_time_ms(bytes_to_copy); ysr@777: double young_other_time_ms = ysr@777: predict_young_other_time_ms(young_length - so_length); ysr@777: double pause_time_ms = ysr@777: base_time_ms + so_time_ms + copy_time_ms + young_other_time_ms; ysr@777: size_t reclaimed_bytes = ysr@777: (young_length - so_length) * HeapRegion::GrainBytes - bytes_to_copy; ysr@777: double gc_eff = (double) reclaimed_bytes / pause_time_ms; ysr@777: ysr@777: *ret_gc_eff = gc_eff; ysr@777: *ret_pause_time_ms = pause_time_ms; ysr@777: } ysr@777: ysr@777: // This is used by: calculate_young_list_target_config(rs_length). It ysr@777: // returns the GC eff of a particular config. It returns false if that ysr@777: // config violates any of the end conditions of the search in the ysr@777: // calling method, or true upon success. The end conditions were put ysr@777: // here since it's called twice and it was best not to replicate them ysr@777: // in the caller. Also, passing the parameteres avoids having to ysr@777: // recalculate them in the innermost loop. ysr@777: bool ysr@777: G1CollectorPolicy::predict_gc_eff(size_t young_length, ysr@777: size_t so_length, ysr@777: double base_time_with_so_ms, ysr@777: size_t init_free_regions, ysr@777: double target_pause_time_ms, ysr@777: double* ret_gc_eff) { ysr@777: *ret_gc_eff = 0.0; ysr@777: ysr@777: if (young_length >= init_free_regions) ysr@777: // end condition 1: not enough space for the young regions ysr@777: return false; ysr@777: ysr@777: double accum_surv_rate_adj = 0.0; ysr@777: if (so_length > 0) ysr@777: accum_surv_rate_adj = accum_yg_surv_rate_pred((int)(so_length - 1)); ysr@777: double accum_surv_rate = ysr@777: accum_yg_surv_rate_pred((int)(young_length - 1)) - accum_surv_rate_adj; ysr@777: size_t bytes_to_copy = ysr@777: (size_t) (accum_surv_rate * (double) HeapRegion::GrainBytes); ysr@777: double copy_time_ms = predict_object_copy_time_ms(bytes_to_copy); ysr@777: double young_other_time_ms = ysr@777: predict_young_other_time_ms(young_length - so_length); ysr@777: double pause_time_ms = ysr@777: base_time_with_so_ms + copy_time_ms + young_other_time_ms; ysr@777: ysr@777: if (pause_time_ms > target_pause_time_ms) ysr@777: // end condition 2: over the target pause time ysr@777: return false; ysr@777: ysr@777: size_t reclaimed_bytes = ysr@777: (young_length - so_length) * HeapRegion::GrainBytes - bytes_to_copy; ysr@777: size_t free_bytes = ysr@777: (init_free_regions - young_length) * HeapRegion::GrainBytes; ysr@777: ysr@777: if ((2.0 + sigma()) * (double) bytes_to_copy > (double) free_bytes) ysr@777: // end condition 3: out of to-space (conservatively) ysr@777: return false; ysr@777: ysr@777: // success! ysr@777: double gc_eff = (double) reclaimed_bytes / pause_time_ms; ysr@777: *ret_gc_eff = gc_eff; ysr@777: ysr@777: return true; ysr@777: } ysr@777: apetrusenko@980: double G1CollectorPolicy::predict_survivor_regions_evac_time() { apetrusenko@980: double survivor_regions_evac_time = 0.0; apetrusenko@980: for (HeapRegion * r = _recorded_survivor_head; apetrusenko@980: r != NULL && r != _recorded_survivor_tail->get_next_young_region(); apetrusenko@980: r = r->get_next_young_region()) { apetrusenko@980: survivor_regions_evac_time += predict_region_elapsed_time_ms(r, true); apetrusenko@980: } apetrusenko@980: return survivor_regions_evac_time; apetrusenko@980: } apetrusenko@980: ysr@777: void G1CollectorPolicy::check_prediction_validity() { ysr@777: guarantee( adaptive_young_list_length(), "should not call this otherwise" ); ysr@777: ysr@777: size_t rs_lengths = _g1->young_list_sampled_rs_lengths(); ysr@777: if (rs_lengths > _rs_lengths_prediction) { ysr@777: // add 10% to avoid having to recalculate often ysr@777: size_t rs_lengths_prediction = rs_lengths * 1100 / 1000; ysr@777: calculate_young_list_target_config(rs_lengths_prediction); ysr@777: } ysr@777: } ysr@777: ysr@777: HeapWord* G1CollectorPolicy::mem_allocate_work(size_t size, ysr@777: bool is_tlab, ysr@777: bool* gc_overhead_limit_was_exceeded) { ysr@777: guarantee(false, "Not using this policy feature yet."); ysr@777: return NULL; ysr@777: } ysr@777: ysr@777: // This method controls how a collector handles one or more ysr@777: // of its generations being fully allocated. ysr@777: HeapWord* G1CollectorPolicy::satisfy_failed_allocation(size_t size, ysr@777: bool is_tlab) { ysr@777: guarantee(false, "Not using this policy feature yet."); ysr@777: return NULL; ysr@777: } ysr@777: ysr@777: ysr@777: #ifndef PRODUCT ysr@777: bool G1CollectorPolicy::verify_young_ages() { ysr@777: HeapRegion* head = _g1->young_list_first_region(); ysr@777: return ysr@777: verify_young_ages(head, _short_lived_surv_rate_group); ysr@777: // also call verify_young_ages on any additional surv rate groups ysr@777: } ysr@777: ysr@777: bool ysr@777: G1CollectorPolicy::verify_young_ages(HeapRegion* head, ysr@777: SurvRateGroup *surv_rate_group) { ysr@777: guarantee( surv_rate_group != NULL, "pre-condition" ); ysr@777: ysr@777: const char* name = surv_rate_group->name(); ysr@777: bool ret = true; ysr@777: int prev_age = -1; ysr@777: ysr@777: for (HeapRegion* curr = head; ysr@777: curr != NULL; ysr@777: curr = curr->get_next_young_region()) { ysr@777: SurvRateGroup* group = curr->surv_rate_group(); ysr@777: if (group == NULL && !curr->is_survivor()) { ysr@777: gclog_or_tty->print_cr("## %s: encountered NULL surv_rate_group", name); ysr@777: ret = false; ysr@777: } ysr@777: ysr@777: if (surv_rate_group == group) { ysr@777: int age = curr->age_in_surv_rate_group(); ysr@777: ysr@777: if (age < 0) { ysr@777: gclog_or_tty->print_cr("## %s: encountered negative age", name); ysr@777: ret = false; ysr@777: } ysr@777: ysr@777: if (age <= prev_age) { ysr@777: gclog_or_tty->print_cr("## %s: region ages are not strictly increasing " ysr@777: "(%d, %d)", name, age, prev_age); ysr@777: ret = false; ysr@777: } ysr@777: prev_age = age; ysr@777: } ysr@777: } ysr@777: ysr@777: return ret; ysr@777: } ysr@777: #endif // PRODUCT ysr@777: ysr@777: void G1CollectorPolicy::record_full_collection_start() { ysr@777: _cur_collection_start_sec = os::elapsedTime(); ysr@777: // Release the future to-space so that it is available for compaction into. ysr@777: _g1->set_full_collection(); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_full_collection_end() { ysr@777: // Consider this like a collection pause for the purposes of allocation ysr@777: // since last pause. ysr@777: double end_sec = os::elapsedTime(); ysr@777: double full_gc_time_sec = end_sec - _cur_collection_start_sec; ysr@777: double full_gc_time_ms = full_gc_time_sec * 1000.0; ysr@777: ysr@777: checkpoint_conc_overhead(); ysr@777: ysr@777: _all_full_gc_times_ms->add(full_gc_time_ms); ysr@777: ysr@777: update_recent_gc_times(end_sec, full_gc_time_sec); ysr@777: ysr@777: _g1->clear_full_collection(); ysr@777: ysr@777: // "Nuke" the heuristics that control the fully/partially young GC ysr@777: // transitions and make sure we start with fully young GCs after the ysr@777: // Full GC. ysr@777: set_full_young_gcs(true); ysr@777: _last_full_young_gc = false; ysr@777: _should_revert_to_full_young_gcs = false; ysr@777: _should_initiate_conc_mark = false; ysr@777: _known_garbage_bytes = 0; ysr@777: _known_garbage_ratio = 0.0; ysr@777: _in_marking_window = false; ysr@777: _in_marking_window_im = false; ysr@777: ysr@777: _short_lived_surv_rate_group->record_scan_only_prefix(0); ysr@777: _short_lived_surv_rate_group->start_adding_regions(); ysr@777: // also call this on any additional surv rate groups ysr@777: apetrusenko@980: record_survivor_regions(0, NULL, NULL); apetrusenko@980: ysr@777: _prev_region_num_young = _region_num_young; ysr@777: _prev_region_num_tenured = _region_num_tenured; ysr@777: ysr@777: _free_regions_at_end_of_collection = _g1->free_regions(); ysr@777: _scan_only_regions_at_end_of_collection = 0; apetrusenko@980: // Reset survivors SurvRateGroup. apetrusenko@980: _survivor_surv_rate_group->reset(); ysr@777: calculate_young_list_min_length(); ysr@777: calculate_young_list_target_config(); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_pop_compute_rc_start() { ysr@777: _pop_compute_rc_start = os::elapsedTime(); ysr@777: } ysr@777: void G1CollectorPolicy::record_pop_compute_rc_end() { ysr@777: double ms = (os::elapsedTime() - _pop_compute_rc_start)*1000.0; ysr@777: _cur_popular_compute_rc_time_ms = ms; ysr@777: _pop_compute_rc_start = 0.0; ysr@777: } ysr@777: void G1CollectorPolicy::record_pop_evac_start() { ysr@777: _pop_evac_start = os::elapsedTime(); ysr@777: } ysr@777: void G1CollectorPolicy::record_pop_evac_end() { ysr@777: double ms = (os::elapsedTime() - _pop_evac_start)*1000.0; ysr@777: _cur_popular_evac_time_ms = ms; ysr@777: _pop_evac_start = 0.0; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_before_bytes(size_t bytes) { ysr@777: _bytes_in_to_space_before_gc += bytes; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_after_bytes(size_t bytes) { ysr@777: _bytes_in_to_space_after_gc += bytes; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_stop_world_start() { ysr@777: _stop_world_start = os::elapsedTime(); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_collection_pause_start(double start_time_sec, ysr@777: size_t start_used) { ysr@777: if (PrintGCDetails) { ysr@777: gclog_or_tty->stamp(PrintGCTimeStamps); ysr@777: gclog_or_tty->print("[GC pause"); ysr@777: if (in_young_gc_mode()) ysr@777: gclog_or_tty->print(" (%s)", full_young_gcs() ? "young" : "partial"); ysr@777: } ysr@777: ysr@777: assert(_g1->used_regions() == _g1->recalculate_used_regions(), ysr@777: "sanity"); ysr@777: ysr@777: double s_w_t_ms = (start_time_sec - _stop_world_start) * 1000.0; ysr@777: _all_stop_world_times_ms->add(s_w_t_ms); ysr@777: _stop_world_start = 0.0; ysr@777: ysr@777: _cur_collection_start_sec = start_time_sec; ysr@777: _cur_collection_pause_used_at_start_bytes = start_used; ysr@777: _cur_collection_pause_used_regions_at_start = _g1->used_regions(); ysr@777: _pending_cards = _g1->pending_card_num(); ysr@777: _max_pending_cards = _g1->max_pending_card_num(); ysr@777: ysr@777: _bytes_in_to_space_before_gc = 0; ysr@777: _bytes_in_to_space_after_gc = 0; ysr@777: _bytes_in_collection_set_before_gc = 0; ysr@777: ysr@777: #ifdef DEBUG ysr@777: // initialise these to something well known so that we can spot ysr@777: // if they are not set properly ysr@777: ysr@777: for (int i = 0; i < _parallel_gc_threads; ++i) { ysr@777: _par_last_ext_root_scan_times_ms[i] = -666.0; ysr@777: _par_last_mark_stack_scan_times_ms[i] = -666.0; ysr@777: _par_last_scan_only_times_ms[i] = -666.0; ysr@777: _par_last_scan_only_regions_scanned[i] = -666.0; ysr@777: _par_last_update_rs_start_times_ms[i] = -666.0; ysr@777: _par_last_update_rs_times_ms[i] = -666.0; ysr@777: _par_last_update_rs_processed_buffers[i] = -666.0; ysr@777: _par_last_scan_rs_start_times_ms[i] = -666.0; ysr@777: _par_last_scan_rs_times_ms[i] = -666.0; ysr@777: _par_last_scan_new_refs_times_ms[i] = -666.0; ysr@777: _par_last_obj_copy_times_ms[i] = -666.0; ysr@777: _par_last_termination_times_ms[i] = -666.0; ysr@777: ysr@777: _pop_par_last_update_rs_start_times_ms[i] = -666.0; ysr@777: _pop_par_last_update_rs_times_ms[i] = -666.0; ysr@777: _pop_par_last_update_rs_processed_buffers[i] = -666.0; ysr@777: _pop_par_last_scan_rs_start_times_ms[i] = -666.0; ysr@777: _pop_par_last_scan_rs_times_ms[i] = -666.0; ysr@777: _pop_par_last_closure_app_times_ms[i] = -666.0; ysr@777: } ysr@777: #endif ysr@777: ysr@777: for (int i = 0; i < _aux_num; ++i) { ysr@777: _cur_aux_times_ms[i] = 0.0; ysr@777: _cur_aux_times_set[i] = false; ysr@777: } ysr@777: ysr@777: _satb_drain_time_set = false; ysr@777: _last_satb_drain_processed_buffers = -1; ysr@777: ysr@777: if (in_young_gc_mode()) ysr@777: _last_young_gc_full = false; ysr@777: ysr@777: ysr@777: // do that for any other surv rate groups ysr@777: _short_lived_surv_rate_group->stop_adding_regions(); ysr@777: size_t short_lived_so_length = _young_list_so_prefix_length; ysr@777: _short_lived_surv_rate_group->record_scan_only_prefix(short_lived_so_length); ysr@777: tag_scan_only(short_lived_so_length); ysr@777: apetrusenko@980: if (G1UseSurvivorSpace) { apetrusenko@980: _survivors_age_table.clear(); apetrusenko@980: } apetrusenko@980: ysr@777: assert( verify_young_ages(), "region age verification" ); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::tag_scan_only(size_t short_lived_scan_only_length) { ysr@777: // done in a way that it can be extended for other surv rate groups too... ysr@777: ysr@777: HeapRegion* head = _g1->young_list_first_region(); ysr@777: bool finished_short_lived = (short_lived_scan_only_length == 0); ysr@777: ysr@777: if (finished_short_lived) ysr@777: return; ysr@777: ysr@777: for (HeapRegion* curr = head; ysr@777: curr != NULL; ysr@777: curr = curr->get_next_young_region()) { ysr@777: SurvRateGroup* surv_rate_group = curr->surv_rate_group(); ysr@777: int age = curr->age_in_surv_rate_group(); ysr@777: ysr@777: if (surv_rate_group == _short_lived_surv_rate_group) { ysr@777: if ((size_t)age < short_lived_scan_only_length) ysr@777: curr->set_scan_only(); ysr@777: else ysr@777: finished_short_lived = true; ysr@777: } ysr@777: ysr@777: ysr@777: if (finished_short_lived) ysr@777: return; ysr@777: } ysr@777: ysr@777: guarantee( false, "we should never reach here" ); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_popular_pause_preamble_start() { ysr@777: _cur_popular_preamble_start_ms = os::elapsedTime() * 1000.0; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_popular_pause_preamble_end() { ysr@777: _cur_popular_preamble_time_ms = ysr@777: (os::elapsedTime() * 1000.0) - _cur_popular_preamble_start_ms; ysr@777: ysr@777: // copy the recorded statistics of the first pass to temporary arrays ysr@777: for (int i = 0; i < _parallel_gc_threads; ++i) { ysr@777: _pop_par_last_update_rs_start_times_ms[i] = _par_last_update_rs_start_times_ms[i]; ysr@777: _pop_par_last_update_rs_times_ms[i] = _par_last_update_rs_times_ms[i]; ysr@777: _pop_par_last_update_rs_processed_buffers[i] = _par_last_update_rs_processed_buffers[i]; ysr@777: _pop_par_last_scan_rs_start_times_ms[i] = _par_last_scan_rs_start_times_ms[i]; ysr@777: _pop_par_last_scan_rs_times_ms[i] = _par_last_scan_rs_times_ms[i]; ysr@777: _pop_par_last_closure_app_times_ms[i] = _par_last_obj_copy_times_ms[i]; ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_mark_closure_time(double mark_closure_time_ms) { ysr@777: _mark_closure_time_ms = mark_closure_time_ms; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_init_start() { ysr@777: _mark_init_start_sec = os::elapsedTime(); ysr@777: guarantee(!in_young_gc_mode(), "should not do be here in young GC mode"); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_init_end_pre(double ysr@777: mark_init_elapsed_time_ms) { ysr@777: _during_marking = true; ysr@777: _should_initiate_conc_mark = false; ysr@777: _cur_mark_stop_world_time_ms = mark_init_elapsed_time_ms; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_init_end() { ysr@777: double end_time_sec = os::elapsedTime(); ysr@777: double elapsed_time_ms = (end_time_sec - _mark_init_start_sec) * 1000.0; ysr@777: _concurrent_mark_init_times_ms->add(elapsed_time_ms); ysr@777: checkpoint_conc_overhead(); ysr@777: record_concurrent_mark_init_end_pre(elapsed_time_ms); ysr@777: ysr@777: _mmu_tracker->add_pause(_mark_init_start_sec, end_time_sec, true); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_remark_start() { ysr@777: _mark_remark_start_sec = os::elapsedTime(); ysr@777: _during_marking = false; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_remark_end() { ysr@777: double end_time_sec = os::elapsedTime(); ysr@777: double elapsed_time_ms = (end_time_sec - _mark_remark_start_sec)*1000.0; ysr@777: checkpoint_conc_overhead(); ysr@777: _concurrent_mark_remark_times_ms->add(elapsed_time_ms); ysr@777: _cur_mark_stop_world_time_ms += elapsed_time_ms; ysr@777: _prev_collection_pause_end_ms += elapsed_time_ms; ysr@777: ysr@777: _mmu_tracker->add_pause(_mark_remark_start_sec, end_time_sec, true); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_mark_cleanup_start() { ysr@777: _mark_cleanup_start_sec = os::elapsedTime(); ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::record_concurrent_mark_cleanup_end(size_t freed_bytes, ysr@777: size_t max_live_bytes) { ysr@777: record_concurrent_mark_cleanup_end_work1(freed_bytes, max_live_bytes); ysr@777: record_concurrent_mark_cleanup_end_work2(); ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy:: ysr@777: record_concurrent_mark_cleanup_end_work1(size_t freed_bytes, ysr@777: size_t max_live_bytes) { ysr@777: if (_n_marks < 2) _n_marks++; ysr@777: if (G1PolicyVerbose > 0) ysr@777: gclog_or_tty->print_cr("At end of marking, max_live is " SIZE_FORMAT " MB " ysr@777: " (of " SIZE_FORMAT " MB heap).", ysr@777: max_live_bytes/M, _g1->capacity()/M); ysr@777: } ysr@777: ysr@777: // The important thing about this is that it includes "os::elapsedTime". ysr@777: void G1CollectorPolicy::record_concurrent_mark_cleanup_end_work2() { ysr@777: checkpoint_conc_overhead(); ysr@777: double end_time_sec = os::elapsedTime(); ysr@777: double elapsed_time_ms = (end_time_sec - _mark_cleanup_start_sec)*1000.0; ysr@777: _concurrent_mark_cleanup_times_ms->add(elapsed_time_ms); ysr@777: _cur_mark_stop_world_time_ms += elapsed_time_ms; ysr@777: _prev_collection_pause_end_ms += elapsed_time_ms; ysr@777: ysr@777: _mmu_tracker->add_pause(_mark_cleanup_start_sec, end_time_sec, true); ysr@777: ysr@777: _num_markings++; ysr@777: ysr@777: // We did a marking, so reset the "since_last_mark" variables. ysr@777: double considerConcMarkCost = 1.0; ysr@777: // If there are available processors, concurrent activity is free... ysr@777: if (Threads::number_of_non_daemon_threads() * 2 < ysr@777: os::active_processor_count()) { ysr@777: considerConcMarkCost = 0.0; ysr@777: } ysr@777: _n_pauses_at_mark_end = _n_pauses; ysr@777: _n_marks_since_last_pause++; ysr@777: _conc_mark_initiated = false; ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::record_concurrent_mark_cleanup_completed() { ysr@777: if (in_young_gc_mode()) { ysr@777: _should_revert_to_full_young_gcs = false; ysr@777: _last_full_young_gc = true; ysr@777: _in_marking_window = false; ysr@777: if (adaptive_young_list_length()) ysr@777: calculate_young_list_target_config(); ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_pause() { ysr@777: if (_stop_world_start > 0.0) { ysr@777: double yield_ms = (os::elapsedTime() - _stop_world_start) * 1000.0; ysr@777: _all_yield_times_ms->add(yield_ms); ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_concurrent_pause_end() { ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_collection_pause_end_CH_strong_roots() { ysr@777: _cur_CH_strong_roots_end_sec = os::elapsedTime(); ysr@777: _cur_CH_strong_roots_dur_ms = ysr@777: (_cur_CH_strong_roots_end_sec - _cur_collection_start_sec) * 1000.0; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::record_collection_pause_end_G1_strong_roots() { ysr@777: _cur_G1_strong_roots_end_sec = os::elapsedTime(); ysr@777: _cur_G1_strong_roots_dur_ms = ysr@777: (_cur_G1_strong_roots_end_sec - _cur_CH_strong_roots_end_sec) * 1000.0; ysr@777: } ysr@777: ysr@777: template ysr@777: T sum_of(T* sum_arr, int start, int n, int N) { ysr@777: T sum = (T)0; ysr@777: for (int i = 0; i < n; i++) { ysr@777: int j = (start + i) % N; ysr@777: sum += sum_arr[j]; ysr@777: } ysr@777: return sum; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_par_stats (int level, ysr@777: const char* str, ysr@777: double* data, ysr@777: bool summary) { ysr@777: double min = data[0], max = data[0]; ysr@777: double total = 0.0; ysr@777: int j; ysr@777: for (j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print("[%s (ms):", str); ysr@777: for (uint i = 0; i < ParallelGCThreads; ++i) { ysr@777: double val = data[i]; ysr@777: if (val < min) ysr@777: min = val; ysr@777: if (val > max) ysr@777: max = val; ysr@777: total += val; ysr@777: gclog_or_tty->print(" %3.1lf", val); ysr@777: } ysr@777: if (summary) { ysr@777: gclog_or_tty->print_cr(""); ysr@777: double avg = total / (double) ParallelGCThreads; ysr@777: gclog_or_tty->print(" "); ysr@777: for (j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print("Avg: %5.1lf, Min: %5.1lf, Max: %5.1lf", ysr@777: avg, min, max); ysr@777: } ysr@777: gclog_or_tty->print_cr("]"); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_par_buffers (int level, ysr@777: const char* str, ysr@777: double* data, ysr@777: bool summary) { ysr@777: double min = data[0], max = data[0]; ysr@777: double total = 0.0; ysr@777: int j; ysr@777: for (j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print("[%s :", str); ysr@777: for (uint i = 0; i < ParallelGCThreads; ++i) { ysr@777: double val = data[i]; ysr@777: if (val < min) ysr@777: min = val; ysr@777: if (val > max) ysr@777: max = val; ysr@777: total += val; ysr@777: gclog_or_tty->print(" %d", (int) val); ysr@777: } ysr@777: if (summary) { ysr@777: gclog_or_tty->print_cr(""); ysr@777: double avg = total / (double) ParallelGCThreads; ysr@777: gclog_or_tty->print(" "); ysr@777: for (j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print("Sum: %d, Avg: %d, Min: %d, Max: %d", ysr@777: (int)total, (int)avg, (int)min, (int)max); ysr@777: } ysr@777: gclog_or_tty->print_cr("]"); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_stats (int level, ysr@777: const char* str, ysr@777: double value) { ysr@777: for (int j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print_cr("[%s: %5.1lf ms]", str, value); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_stats (int level, ysr@777: const char* str, ysr@777: int value) { ysr@777: for (int j = 0; j < level; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: gclog_or_tty->print_cr("[%s: %d]", str, value); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::avg_value (double* data) { ysr@777: if (ParallelGCThreads > 0) { ysr@777: double ret = 0.0; ysr@777: for (uint i = 0; i < ParallelGCThreads; ++i) ysr@777: ret += data[i]; ysr@777: return ret / (double) ParallelGCThreads; ysr@777: } else { ysr@777: return data[0]; ysr@777: } ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::max_value (double* data) { ysr@777: if (ParallelGCThreads > 0) { ysr@777: double ret = data[0]; ysr@777: for (uint i = 1; i < ParallelGCThreads; ++i) ysr@777: if (data[i] > ret) ysr@777: ret = data[i]; ysr@777: return ret; ysr@777: } else { ysr@777: return data[0]; ysr@777: } ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::sum_of_values (double* data) { ysr@777: if (ParallelGCThreads > 0) { ysr@777: double sum = 0.0; ysr@777: for (uint i = 0; i < ParallelGCThreads; i++) ysr@777: sum += data[i]; ysr@777: return sum; ysr@777: } else { ysr@777: return data[0]; ysr@777: } ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::max_sum (double* data1, ysr@777: double* data2) { ysr@777: double ret = data1[0] + data2[0]; ysr@777: ysr@777: if (ParallelGCThreads > 0) { ysr@777: for (uint i = 1; i < ParallelGCThreads; ++i) { ysr@777: double data = data1[i] + data2[i]; ysr@777: if (data > ret) ysr@777: ret = data; ysr@777: } ysr@777: } ysr@777: return ret; ysr@777: } ysr@777: ysr@777: // Anything below that is considered to be zero ysr@777: #define MIN_TIMER_GRANULARITY 0.0000001 ysr@777: ysr@777: void G1CollectorPolicy::record_collection_pause_end(bool popular, ysr@777: bool abandoned) { ysr@777: double end_time_sec = os::elapsedTime(); ysr@777: double elapsed_ms = _last_pause_time_ms; ysr@777: bool parallel = ParallelGCThreads > 0; ysr@777: double evac_ms = (end_time_sec - _cur_G1_strong_roots_end_sec) * 1000.0; ysr@777: size_t rs_size = ysr@777: _cur_collection_pause_used_regions_at_start - collection_set_size(); ysr@777: size_t cur_used_bytes = _g1->used(); ysr@777: assert(cur_used_bytes == _g1->recalculate_used(), "It should!"); ysr@777: bool last_pause_included_initial_mark = false; ysr@777: ysr@777: #ifndef PRODUCT ysr@777: if (G1YoungSurvRateVerbose) { ysr@777: gclog_or_tty->print_cr(""); ysr@777: _short_lived_surv_rate_group->print(); ysr@777: // do that for any other surv rate groups too ysr@777: } ysr@777: #endif // PRODUCT ysr@777: ysr@777: checkpoint_conc_overhead(); ysr@777: ysr@777: if (in_young_gc_mode()) { ysr@777: last_pause_included_initial_mark = _should_initiate_conc_mark; ysr@777: if (last_pause_included_initial_mark) ysr@777: record_concurrent_mark_init_end_pre(0.0); ysr@777: ysr@777: size_t min_used_targ = ysr@777: (_g1->capacity() / 100) * (G1SteadyStateUsed - G1SteadyStateUsedDelta); ysr@777: ysr@777: if (cur_used_bytes > min_used_targ) { ysr@777: if (cur_used_bytes <= _prev_collection_pause_used_at_end_bytes) { ysr@777: } else if (!_g1->mark_in_progress() && !_last_full_young_gc) { ysr@777: _should_initiate_conc_mark = true; ysr@777: } ysr@777: } ysr@777: ysr@777: _prev_collection_pause_used_at_end_bytes = cur_used_bytes; ysr@777: } ysr@777: ysr@777: _mmu_tracker->add_pause(end_time_sec - elapsed_ms/1000.0, ysr@777: end_time_sec, false); ysr@777: ysr@777: guarantee(_cur_collection_pause_used_regions_at_start >= ysr@777: collection_set_size(), ysr@777: "Negative RS size?"); ysr@777: ysr@777: // This assert is exempted when we're doing parallel collection pauses, ysr@777: // because the fragmentation caused by the parallel GC allocation buffers ysr@777: // can lead to more memory being used during collection than was used ysr@777: // before. Best leave this out until the fragmentation problem is fixed. ysr@777: // Pauses in which evacuation failed can also lead to negative ysr@777: // collections, since no space is reclaimed from a region containing an ysr@777: // object whose evacuation failed. ysr@777: // Further, we're now always doing parallel collection. But I'm still ysr@777: // leaving this here as a placeholder for a more precise assertion later. ysr@777: // (DLD, 10/05.) ysr@777: assert((true || parallel) // Always using GC LABs now. ysr@777: || _g1->evacuation_failed() ysr@777: || _cur_collection_pause_used_at_start_bytes >= cur_used_bytes, ysr@777: "Negative collection"); ysr@777: ysr@777: size_t freed_bytes = ysr@777: _cur_collection_pause_used_at_start_bytes - cur_used_bytes; ysr@777: size_t surviving_bytes = _collection_set_bytes_used_before - freed_bytes; ysr@777: double survival_fraction = ysr@777: (double)surviving_bytes/ ysr@777: (double)_collection_set_bytes_used_before; ysr@777: ysr@777: _n_pauses++; ysr@777: ysr@777: if (!abandoned) { ysr@777: _recent_CH_strong_roots_times_ms->add(_cur_CH_strong_roots_dur_ms); ysr@777: _recent_G1_strong_roots_times_ms->add(_cur_G1_strong_roots_dur_ms); ysr@777: _recent_evac_times_ms->add(evac_ms); ysr@777: _recent_pause_times_ms->add(elapsed_ms); ysr@777: ysr@777: _recent_rs_sizes->add(rs_size); ysr@777: ysr@777: // We exempt parallel collection from this check because Alloc Buffer ysr@777: // fragmentation can produce negative collections. Same with evac ysr@777: // failure. ysr@777: // Further, we're now always doing parallel collection. But I'm still ysr@777: // leaving this here as a placeholder for a more precise assertion later. ysr@777: // (DLD, 10/05. ysr@777: assert((true || parallel) ysr@777: || _g1->evacuation_failed() ysr@777: || surviving_bytes <= _collection_set_bytes_used_before, ysr@777: "Or else negative collection!"); ysr@777: _recent_CS_bytes_used_before->add(_collection_set_bytes_used_before); ysr@777: _recent_CS_bytes_surviving->add(surviving_bytes); ysr@777: ysr@777: // this is where we update the allocation rate of the application ysr@777: double app_time_ms = ysr@777: (_cur_collection_start_sec * 1000.0 - _prev_collection_pause_end_ms); ysr@777: if (app_time_ms < MIN_TIMER_GRANULARITY) { ysr@777: // This usually happens due to the timer not having the required ysr@777: // granularity. Some Linuxes are the usual culprits. ysr@777: // We'll just set it to something (arbitrarily) small. ysr@777: app_time_ms = 1.0; ysr@777: } ysr@777: size_t regions_allocated = ysr@777: (_region_num_young - _prev_region_num_young) + ysr@777: (_region_num_tenured - _prev_region_num_tenured); ysr@777: double alloc_rate_ms = (double) regions_allocated / app_time_ms; ysr@777: _alloc_rate_ms_seq->add(alloc_rate_ms); ysr@777: _prev_region_num_young = _region_num_young; ysr@777: _prev_region_num_tenured = _region_num_tenured; ysr@777: ysr@777: double interval_ms = ysr@777: (end_time_sec - _recent_prev_end_times_for_all_gcs_sec->oldest()) * 1000.0; ysr@777: update_recent_gc_times(end_time_sec, elapsed_ms); ysr@777: _recent_avg_pause_time_ratio = _recent_gc_times_ms->sum()/interval_ms; ysr@777: assert(recent_avg_pause_time_ratio() < 1.00, "All GC?"); ysr@777: } ysr@777: ysr@777: if (G1PolicyVerbose > 1) { ysr@777: gclog_or_tty->print_cr(" Recording collection pause(%d)", _n_pauses); ysr@777: } ysr@777: ysr@777: PauseSummary* summary; ysr@777: if (!abandoned && !popular) ysr@777: summary = _non_pop_summary; ysr@777: else if (!abandoned && popular) ysr@777: summary = _pop_summary; ysr@777: else if (abandoned && !popular) ysr@777: summary = _non_pop_abandoned_summary; ysr@777: else if (abandoned && popular) ysr@777: summary = _pop_abandoned_summary; ysr@777: else ysr@777: guarantee(false, "should not get here!"); ysr@777: ysr@777: double pop_update_rs_time; ysr@777: double pop_update_rs_processed_buffers; ysr@777: double pop_scan_rs_time; ysr@777: double pop_closure_app_time; ysr@777: double pop_other_time; ysr@777: ysr@777: if (popular) { ysr@777: PopPreambleSummary* preamble_summary = summary->pop_preamble_summary(); ysr@777: guarantee(preamble_summary != NULL, "should not be null!"); ysr@777: ysr@777: pop_update_rs_time = avg_value(_pop_par_last_update_rs_times_ms); ysr@777: pop_update_rs_processed_buffers = ysr@777: sum_of_values(_pop_par_last_update_rs_processed_buffers); ysr@777: pop_scan_rs_time = avg_value(_pop_par_last_scan_rs_times_ms); ysr@777: pop_closure_app_time = avg_value(_pop_par_last_closure_app_times_ms); ysr@777: pop_other_time = _cur_popular_preamble_time_ms - ysr@777: (pop_update_rs_time + pop_scan_rs_time + pop_closure_app_time + ysr@777: _cur_popular_evac_time_ms); ysr@777: ysr@777: preamble_summary->record_pop_preamble_time_ms(_cur_popular_preamble_time_ms); ysr@777: preamble_summary->record_pop_update_rs_time_ms(pop_update_rs_time); ysr@777: preamble_summary->record_pop_scan_rs_time_ms(pop_scan_rs_time); ysr@777: preamble_summary->record_pop_closure_app_time_ms(pop_closure_app_time); ysr@777: preamble_summary->record_pop_evacuation_time_ms(_cur_popular_evac_time_ms); ysr@777: preamble_summary->record_pop_other_time_ms(pop_other_time); ysr@777: } ysr@777: ysr@777: double ext_root_scan_time = avg_value(_par_last_ext_root_scan_times_ms); ysr@777: double mark_stack_scan_time = avg_value(_par_last_mark_stack_scan_times_ms); ysr@777: double scan_only_time = avg_value(_par_last_scan_only_times_ms); ysr@777: double scan_only_regions_scanned = ysr@777: sum_of_values(_par_last_scan_only_regions_scanned); ysr@777: double update_rs_time = avg_value(_par_last_update_rs_times_ms); ysr@777: double update_rs_processed_buffers = ysr@777: sum_of_values(_par_last_update_rs_processed_buffers); ysr@777: double scan_rs_time = avg_value(_par_last_scan_rs_times_ms); ysr@777: double obj_copy_time = avg_value(_par_last_obj_copy_times_ms); ysr@777: double termination_time = avg_value(_par_last_termination_times_ms); ysr@777: ysr@777: double parallel_other_time; ysr@777: if (!abandoned) { ysr@777: MainBodySummary* body_summary = summary->main_body_summary(); ysr@777: guarantee(body_summary != NULL, "should not be null!"); ysr@777: ysr@777: if (_satb_drain_time_set) ysr@777: body_summary->record_satb_drain_time_ms(_cur_satb_drain_time_ms); ysr@777: else ysr@777: body_summary->record_satb_drain_time_ms(0.0); ysr@777: body_summary->record_ext_root_scan_time_ms(ext_root_scan_time); ysr@777: body_summary->record_mark_stack_scan_time_ms(mark_stack_scan_time); ysr@777: body_summary->record_scan_only_time_ms(scan_only_time); ysr@777: body_summary->record_update_rs_time_ms(update_rs_time); ysr@777: body_summary->record_scan_rs_time_ms(scan_rs_time); ysr@777: body_summary->record_obj_copy_time_ms(obj_copy_time); ysr@777: if (parallel) { ysr@777: body_summary->record_parallel_time_ms(_cur_collection_par_time_ms); ysr@777: body_summary->record_clear_ct_time_ms(_cur_clear_ct_time_ms); ysr@777: body_summary->record_termination_time_ms(termination_time); ysr@777: parallel_other_time = _cur_collection_par_time_ms - ysr@777: (update_rs_time + ext_root_scan_time + mark_stack_scan_time + ysr@777: scan_only_time + scan_rs_time + obj_copy_time + termination_time); ysr@777: body_summary->record_parallel_other_time_ms(parallel_other_time); ysr@777: } ysr@777: body_summary->record_mark_closure_time_ms(_mark_closure_time_ms); ysr@777: } ysr@777: ysr@777: if (G1PolicyVerbose > 1) { ysr@777: gclog_or_tty->print_cr(" ET: %10.6f ms (avg: %10.6f ms)\n" ysr@777: " CH Strong: %10.6f ms (avg: %10.6f ms)\n" ysr@777: " G1 Strong: %10.6f ms (avg: %10.6f ms)\n" ysr@777: " Evac: %10.6f ms (avg: %10.6f ms)\n" ysr@777: " ET-RS: %10.6f ms (avg: %10.6f ms)\n" ysr@777: " |RS|: " SIZE_FORMAT, ysr@777: elapsed_ms, recent_avg_time_for_pauses_ms(), ysr@777: _cur_CH_strong_roots_dur_ms, recent_avg_time_for_CH_strong_ms(), ysr@777: _cur_G1_strong_roots_dur_ms, recent_avg_time_for_G1_strong_ms(), ysr@777: evac_ms, recent_avg_time_for_evac_ms(), ysr@777: scan_rs_time, ysr@777: recent_avg_time_for_pauses_ms() - ysr@777: recent_avg_time_for_G1_strong_ms(), ysr@777: rs_size); ysr@777: ysr@777: gclog_or_tty->print_cr(" Used at start: " SIZE_FORMAT"K" ysr@777: " At end " SIZE_FORMAT "K\n" ysr@777: " garbage : " SIZE_FORMAT "K" ysr@777: " of " SIZE_FORMAT "K\n" ysr@777: " survival : %6.2f%% (%6.2f%% avg)", ysr@777: _cur_collection_pause_used_at_start_bytes/K, ysr@777: _g1->used()/K, freed_bytes/K, ysr@777: _collection_set_bytes_used_before/K, ysr@777: survival_fraction*100.0, ysr@777: recent_avg_survival_fraction()*100.0); ysr@777: gclog_or_tty->print_cr(" Recent %% gc pause time: %6.2f", ysr@777: recent_avg_pause_time_ratio() * 100.0); ysr@777: } ysr@777: ysr@777: double other_time_ms = elapsed_ms; ysr@777: if (popular) ysr@777: other_time_ms -= _cur_popular_preamble_time_ms; ysr@777: ysr@777: if (!abandoned) { ysr@777: if (_satb_drain_time_set) ysr@777: other_time_ms -= _cur_satb_drain_time_ms; ysr@777: ysr@777: if (parallel) ysr@777: other_time_ms -= _cur_collection_par_time_ms + _cur_clear_ct_time_ms; ysr@777: else ysr@777: other_time_ms -= ysr@777: update_rs_time + ysr@777: ext_root_scan_time + mark_stack_scan_time + scan_only_time + ysr@777: scan_rs_time + obj_copy_time; ysr@777: } ysr@777: ysr@777: if (PrintGCDetails) { ysr@777: gclog_or_tty->print_cr("%s%s, %1.8lf secs]", ysr@777: (popular && !abandoned) ? " (popular)" : ysr@777: (!popular && abandoned) ? " (abandoned)" : ysr@777: (popular && abandoned) ? " (popular/abandoned)" : "", ysr@777: (last_pause_included_initial_mark) ? " (initial-mark)" : "", ysr@777: elapsed_ms / 1000.0); ysr@777: ysr@777: if (!abandoned) { ysr@777: if (_satb_drain_time_set) ysr@777: print_stats(1, "SATB Drain Time", _cur_satb_drain_time_ms); ysr@777: if (_last_satb_drain_processed_buffers >= 0) ysr@777: print_stats(2, "Processed Buffers", _last_satb_drain_processed_buffers); ysr@777: } ysr@777: if (popular) ysr@777: print_stats(1, "Popularity Preamble", _cur_popular_preamble_time_ms); ysr@777: if (parallel) { ysr@777: if (popular) { ysr@777: print_par_stats(2, "Update RS (Start)", _pop_par_last_update_rs_start_times_ms, false); ysr@777: print_par_stats(2, "Update RS", _pop_par_last_update_rs_times_ms); ysr@777: if (G1RSBarrierUseQueue) ysr@777: print_par_buffers(3, "Processed Buffers", ysr@777: _pop_par_last_update_rs_processed_buffers, true); ysr@777: print_par_stats(2, "Scan RS", _pop_par_last_scan_rs_times_ms); ysr@777: print_par_stats(2, "Closure app", _pop_par_last_closure_app_times_ms); ysr@777: print_stats(2, "Evacuation", _cur_popular_evac_time_ms); ysr@777: print_stats(2, "Other", pop_other_time); ysr@777: } ysr@777: if (!abandoned) { ysr@777: print_stats(1, "Parallel Time", _cur_collection_par_time_ms); ysr@777: if (!popular) { ysr@777: print_par_stats(2, "Update RS (Start)", _par_last_update_rs_start_times_ms, false); ysr@777: print_par_stats(2, "Update RS", _par_last_update_rs_times_ms); ysr@777: if (G1RSBarrierUseQueue) ysr@777: print_par_buffers(3, "Processed Buffers", ysr@777: _par_last_update_rs_processed_buffers, true); ysr@777: } ysr@777: print_par_stats(2, "Ext Root Scanning", _par_last_ext_root_scan_times_ms); ysr@777: print_par_stats(2, "Mark Stack Scanning", _par_last_mark_stack_scan_times_ms); ysr@777: print_par_stats(2, "Scan-Only Scanning", _par_last_scan_only_times_ms); ysr@777: print_par_buffers(3, "Scan-Only Regions", ysr@777: _par_last_scan_only_regions_scanned, true); ysr@777: print_par_stats(2, "Scan RS", _par_last_scan_rs_times_ms); ysr@777: print_par_stats(2, "Object Copy", _par_last_obj_copy_times_ms); ysr@777: print_par_stats(2, "Termination", _par_last_termination_times_ms); ysr@777: print_stats(2, "Other", parallel_other_time); ysr@777: print_stats(1, "Clear CT", _cur_clear_ct_time_ms); ysr@777: } ysr@777: } else { ysr@777: if (popular) { ysr@777: print_stats(2, "Update RS", pop_update_rs_time); ysr@777: if (G1RSBarrierUseQueue) ysr@777: print_stats(3, "Processed Buffers", ysr@777: (int)pop_update_rs_processed_buffers); ysr@777: print_stats(2, "Scan RS", pop_scan_rs_time); ysr@777: print_stats(2, "Closure App", pop_closure_app_time); ysr@777: print_stats(2, "Evacuation", _cur_popular_evac_time_ms); ysr@777: print_stats(2, "Other", pop_other_time); ysr@777: } ysr@777: if (!abandoned) { ysr@777: if (!popular) { ysr@777: print_stats(1, "Update RS", update_rs_time); ysr@777: if (G1RSBarrierUseQueue) ysr@777: print_stats(2, "Processed Buffers", ysr@777: (int)update_rs_processed_buffers); ysr@777: } ysr@777: print_stats(1, "Ext Root Scanning", ext_root_scan_time); ysr@777: print_stats(1, "Mark Stack Scanning", mark_stack_scan_time); ysr@777: print_stats(1, "Scan-Only Scanning", scan_only_time); ysr@777: print_stats(1, "Scan RS", scan_rs_time); ysr@777: print_stats(1, "Object Copying", obj_copy_time); ysr@777: } ysr@777: } ysr@777: print_stats(1, "Other", other_time_ms); ysr@777: for (int i = 0; i < _aux_num; ++i) { ysr@777: if (_cur_aux_times_set[i]) { ysr@777: char buffer[96]; ysr@777: sprintf(buffer, "Aux%d", i); ysr@777: print_stats(1, buffer, _cur_aux_times_ms[i]); ysr@777: } ysr@777: } ysr@777: } ysr@777: if (PrintGCDetails) ysr@777: gclog_or_tty->print(" ["); ysr@777: if (PrintGC || PrintGCDetails) ysr@777: _g1->print_size_transition(gclog_or_tty, ysr@777: _cur_collection_pause_used_at_start_bytes, ysr@777: _g1->used(), _g1->capacity()); ysr@777: if (PrintGCDetails) ysr@777: gclog_or_tty->print_cr("]"); ysr@777: ysr@777: _all_pause_times_ms->add(elapsed_ms); ysr@777: summary->record_total_time_ms(elapsed_ms); ysr@777: summary->record_other_time_ms(other_time_ms); ysr@777: for (int i = 0; i < _aux_num; ++i) ysr@777: if (_cur_aux_times_set[i]) ysr@777: _all_aux_times_ms[i].add(_cur_aux_times_ms[i]); ysr@777: ysr@777: // Reset marks-between-pauses counter. ysr@777: _n_marks_since_last_pause = 0; ysr@777: ysr@777: // Update the efficiency-since-mark vars. ysr@777: double proc_ms = elapsed_ms * (double) _parallel_gc_threads; ysr@777: if (elapsed_ms < MIN_TIMER_GRANULARITY) { ysr@777: // This usually happens due to the timer not having the required ysr@777: // granularity. Some Linuxes are the usual culprits. ysr@777: // We'll just set it to something (arbitrarily) small. ysr@777: proc_ms = 1.0; ysr@777: } ysr@777: double cur_efficiency = (double) freed_bytes / proc_ms; ysr@777: ysr@777: bool new_in_marking_window = _in_marking_window; ysr@777: bool new_in_marking_window_im = false; ysr@777: if (_should_initiate_conc_mark) { ysr@777: new_in_marking_window = true; ysr@777: new_in_marking_window_im = true; ysr@777: } ysr@777: ysr@777: if (in_young_gc_mode()) { ysr@777: if (_last_full_young_gc) { ysr@777: set_full_young_gcs(false); ysr@777: _last_full_young_gc = false; ysr@777: } ysr@777: ysr@777: if ( !_last_young_gc_full ) { ysr@777: if ( _should_revert_to_full_young_gcs || ysr@777: _known_garbage_ratio < 0.05 || ysr@777: (adaptive_young_list_length() && ysr@777: (get_gc_eff_factor() * cur_efficiency < predict_young_gc_eff())) ) { ysr@777: set_full_young_gcs(true); ysr@777: } ysr@777: } ysr@777: _should_revert_to_full_young_gcs = false; ysr@777: ysr@777: if (_last_young_gc_full && !_during_marking) ysr@777: _young_gc_eff_seq->add(cur_efficiency); ysr@777: } ysr@777: ysr@777: _short_lived_surv_rate_group->start_adding_regions(); ysr@777: // do that for any other surv rate groupsx ysr@777: ysr@777: // ysr@777: ysr@777: if (!popular && !abandoned) { ysr@777: double pause_time_ms = elapsed_ms; ysr@777: ysr@777: size_t diff = 0; ysr@777: if (_max_pending_cards >= _pending_cards) ysr@777: diff = _max_pending_cards - _pending_cards; ysr@777: _pending_card_diff_seq->add((double) diff); ysr@777: ysr@777: double cost_per_card_ms = 0.0; ysr@777: if (_pending_cards > 0) { ysr@777: cost_per_card_ms = update_rs_time / (double) _pending_cards; ysr@777: _cost_per_card_ms_seq->add(cost_per_card_ms); ysr@777: } ysr@777: ysr@777: double cost_per_scan_only_region_ms = 0.0; ysr@777: if (scan_only_regions_scanned > 0.0) { ysr@777: cost_per_scan_only_region_ms = ysr@777: scan_only_time / scan_only_regions_scanned; ysr@777: if (_in_marking_window_im) ysr@777: _cost_per_scan_only_region_ms_during_cm_seq->add(cost_per_scan_only_region_ms); ysr@777: else ysr@777: _cost_per_scan_only_region_ms_seq->add(cost_per_scan_only_region_ms); ysr@777: } ysr@777: ysr@777: size_t cards_scanned = _g1->cards_scanned(); ysr@777: ysr@777: double cost_per_entry_ms = 0.0; ysr@777: if (cards_scanned > 10) { ysr@777: cost_per_entry_ms = scan_rs_time / (double) cards_scanned; ysr@777: if (_last_young_gc_full) ysr@777: _cost_per_entry_ms_seq->add(cost_per_entry_ms); ysr@777: else ysr@777: _partially_young_cost_per_entry_ms_seq->add(cost_per_entry_ms); ysr@777: } ysr@777: ysr@777: if (_max_rs_lengths > 0) { ysr@777: double cards_per_entry_ratio = ysr@777: (double) cards_scanned / (double) _max_rs_lengths; ysr@777: if (_last_young_gc_full) ysr@777: _fully_young_cards_per_entry_ratio_seq->add(cards_per_entry_ratio); ysr@777: else ysr@777: _partially_young_cards_per_entry_ratio_seq->add(cards_per_entry_ratio); ysr@777: } ysr@777: ysr@777: size_t rs_length_diff = _max_rs_lengths - _recorded_rs_lengths; ysr@777: if (rs_length_diff >= 0) ysr@777: _rs_length_diff_seq->add((double) rs_length_diff); ysr@777: ysr@777: size_t copied_bytes = surviving_bytes; ysr@777: double cost_per_byte_ms = 0.0; ysr@777: if (copied_bytes > 0) { ysr@777: cost_per_byte_ms = obj_copy_time / (double) copied_bytes; ysr@777: if (_in_marking_window) ysr@777: _cost_per_byte_ms_during_cm_seq->add(cost_per_byte_ms); ysr@777: else ysr@777: _cost_per_byte_ms_seq->add(cost_per_byte_ms); ysr@777: } ysr@777: ysr@777: double all_other_time_ms = pause_time_ms - ysr@777: (update_rs_time + scan_only_time + scan_rs_time + obj_copy_time + ysr@777: _mark_closure_time_ms + termination_time); ysr@777: ysr@777: double young_other_time_ms = 0.0; ysr@777: if (_recorded_young_regions > 0) { ysr@777: young_other_time_ms = ysr@777: _recorded_young_cset_choice_time_ms + ysr@777: _recorded_young_free_cset_time_ms; ysr@777: _young_other_cost_per_region_ms_seq->add(young_other_time_ms / ysr@777: (double) _recorded_young_regions); ysr@777: } ysr@777: double non_young_other_time_ms = 0.0; ysr@777: if (_recorded_non_young_regions > 0) { ysr@777: non_young_other_time_ms = ysr@777: _recorded_non_young_cset_choice_time_ms + ysr@777: _recorded_non_young_free_cset_time_ms; ysr@777: ysr@777: _non_young_other_cost_per_region_ms_seq->add(non_young_other_time_ms / ysr@777: (double) _recorded_non_young_regions); ysr@777: } ysr@777: ysr@777: double constant_other_time_ms = all_other_time_ms - ysr@777: (young_other_time_ms + non_young_other_time_ms); ysr@777: _constant_other_time_ms_seq->add(constant_other_time_ms); ysr@777: ysr@777: double survival_ratio = 0.0; ysr@777: if (_bytes_in_collection_set_before_gc > 0) { ysr@777: survival_ratio = (double) bytes_in_to_space_during_gc() / ysr@777: (double) _bytes_in_collection_set_before_gc; ysr@777: } ysr@777: ysr@777: _pending_cards_seq->add((double) _pending_cards); ysr@777: _scanned_cards_seq->add((double) cards_scanned); ysr@777: _rs_lengths_seq->add((double) _max_rs_lengths); ysr@777: ysr@777: double expensive_region_limit_ms = ysr@777: (double) G1MaxPauseTimeMS - predict_constant_other_time_ms(); ysr@777: if (expensive_region_limit_ms < 0.0) { ysr@777: // this means that the other time was predicted to be longer than ysr@777: // than the max pause time ysr@777: expensive_region_limit_ms = (double) G1MaxPauseTimeMS; ysr@777: } ysr@777: _expensive_region_limit_ms = expensive_region_limit_ms; ysr@777: ysr@777: if (PREDICTIONS_VERBOSE) { ysr@777: gclog_or_tty->print_cr(""); ysr@777: gclog_or_tty->print_cr("PREDICTIONS %1.4lf %d " ysr@777: "REGIONS %d %d %d %d " ysr@777: "PENDING_CARDS %d %d " ysr@777: "CARDS_SCANNED %d %d " ysr@777: "RS_LENGTHS %d %d " ysr@777: "SCAN_ONLY_SCAN %1.6lf %1.6lf " ysr@777: "RS_UPDATE %1.6lf %1.6lf RS_SCAN %1.6lf %1.6lf " ysr@777: "SURVIVAL_RATIO %1.6lf %1.6lf " ysr@777: "OBJECT_COPY %1.6lf %1.6lf OTHER_CONSTANT %1.6lf %1.6lf " ysr@777: "OTHER_YOUNG %1.6lf %1.6lf " ysr@777: "OTHER_NON_YOUNG %1.6lf %1.6lf " ysr@777: "VTIME_DIFF %1.6lf TERMINATION %1.6lf " ysr@777: "ELAPSED %1.6lf %1.6lf ", ysr@777: _cur_collection_start_sec, ysr@777: (!_last_young_gc_full) ? 2 : ysr@777: (last_pause_included_initial_mark) ? 1 : 0, ysr@777: _recorded_region_num, ysr@777: _recorded_young_regions, ysr@777: _recorded_scan_only_regions, ysr@777: _recorded_non_young_regions, ysr@777: _predicted_pending_cards, _pending_cards, ysr@777: _predicted_cards_scanned, cards_scanned, ysr@777: _predicted_rs_lengths, _max_rs_lengths, ysr@777: _predicted_scan_only_scan_time_ms, scan_only_time, ysr@777: _predicted_rs_update_time_ms, update_rs_time, ysr@777: _predicted_rs_scan_time_ms, scan_rs_time, ysr@777: _predicted_survival_ratio, survival_ratio, ysr@777: _predicted_object_copy_time_ms, obj_copy_time, ysr@777: _predicted_constant_other_time_ms, constant_other_time_ms, ysr@777: _predicted_young_other_time_ms, young_other_time_ms, ysr@777: _predicted_non_young_other_time_ms, ysr@777: non_young_other_time_ms, ysr@777: _vtime_diff_ms, termination_time, ysr@777: _predicted_pause_time_ms, elapsed_ms); ysr@777: } ysr@777: ysr@777: if (G1PolicyVerbose > 0) { ysr@777: gclog_or_tty->print_cr("Pause Time, predicted: %1.4lfms (predicted %s), actual: %1.4lfms", ysr@777: _predicted_pause_time_ms, ysr@777: (_within_target) ? "within" : "outside", ysr@777: elapsed_ms); ysr@777: } ysr@777: ysr@777: } ysr@777: ysr@777: _in_marking_window = new_in_marking_window; ysr@777: _in_marking_window_im = new_in_marking_window_im; ysr@777: _free_regions_at_end_of_collection = _g1->free_regions(); ysr@777: _scan_only_regions_at_end_of_collection = _g1->young_list_length(); ysr@777: calculate_young_list_min_length(); ysr@777: calculate_young_list_target_config(); ysr@777: ysr@777: // ysr@777: ysr@777: _target_pause_time_ms = -1.0; ysr@777: } ysr@777: ysr@777: // ysr@777: ysr@777: double ysr@777: G1CollectorPolicy:: ysr@777: predict_young_collection_elapsed_time_ms(size_t adjustment) { ysr@777: guarantee( adjustment == 0 || adjustment == 1, "invariant" ); ysr@777: ysr@777: G1CollectedHeap* g1h = G1CollectedHeap::heap(); ysr@777: size_t young_num = g1h->young_list_length(); ysr@777: if (young_num == 0) ysr@777: return 0.0; ysr@777: ysr@777: young_num += adjustment; ysr@777: size_t pending_cards = predict_pending_cards(); ysr@777: size_t rs_lengths = g1h->young_list_sampled_rs_lengths() + ysr@777: predict_rs_length_diff(); ysr@777: size_t card_num; ysr@777: if (full_young_gcs()) ysr@777: card_num = predict_young_card_num(rs_lengths); ysr@777: else ysr@777: card_num = predict_non_young_card_num(rs_lengths); ysr@777: size_t young_byte_size = young_num * HeapRegion::GrainBytes; ysr@777: double accum_yg_surv_rate = ysr@777: _short_lived_surv_rate_group->accum_surv_rate(adjustment); ysr@777: ysr@777: size_t bytes_to_copy = ysr@777: (size_t) (accum_yg_surv_rate * (double) HeapRegion::GrainBytes); ysr@777: ysr@777: return ysr@777: predict_rs_update_time_ms(pending_cards) + ysr@777: predict_rs_scan_time_ms(card_num) + ysr@777: predict_object_copy_time_ms(bytes_to_copy) + ysr@777: predict_young_other_time_ms(young_num) + ysr@777: predict_constant_other_time_ms(); ysr@777: } ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::predict_base_elapsed_time_ms(size_t pending_cards) { ysr@777: size_t rs_length = predict_rs_length_diff(); ysr@777: size_t card_num; ysr@777: if (full_young_gcs()) ysr@777: card_num = predict_young_card_num(rs_length); ysr@777: else ysr@777: card_num = predict_non_young_card_num(rs_length); ysr@777: return predict_base_elapsed_time_ms(pending_cards, card_num); ysr@777: } ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::predict_base_elapsed_time_ms(size_t pending_cards, ysr@777: size_t scanned_cards) { ysr@777: return ysr@777: predict_rs_update_time_ms(pending_cards) + ysr@777: predict_rs_scan_time_ms(scanned_cards) + ysr@777: predict_constant_other_time_ms(); ysr@777: } ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::predict_region_elapsed_time_ms(HeapRegion* hr, ysr@777: bool young) { ysr@777: size_t rs_length = hr->rem_set()->occupied(); ysr@777: size_t card_num; ysr@777: if (full_young_gcs()) ysr@777: card_num = predict_young_card_num(rs_length); ysr@777: else ysr@777: card_num = predict_non_young_card_num(rs_length); ysr@777: size_t bytes_to_copy = predict_bytes_to_copy(hr); ysr@777: ysr@777: double region_elapsed_time_ms = ysr@777: predict_rs_scan_time_ms(card_num) + ysr@777: predict_object_copy_time_ms(bytes_to_copy); ysr@777: ysr@777: if (young) ysr@777: region_elapsed_time_ms += predict_young_other_time_ms(1); ysr@777: else ysr@777: region_elapsed_time_ms += predict_non_young_other_time_ms(1); ysr@777: ysr@777: return region_elapsed_time_ms; ysr@777: } ysr@777: ysr@777: size_t ysr@777: G1CollectorPolicy::predict_bytes_to_copy(HeapRegion* hr) { ysr@777: size_t bytes_to_copy; ysr@777: if (hr->is_marked()) ysr@777: bytes_to_copy = hr->max_live_bytes(); ysr@777: else { ysr@777: guarantee( hr->is_young() && hr->age_in_surv_rate_group() != -1, ysr@777: "invariant" ); ysr@777: int age = hr->age_in_surv_rate_group(); apetrusenko@980: double yg_surv_rate = predict_yg_surv_rate(age, hr->surv_rate_group()); ysr@777: bytes_to_copy = (size_t) ((double) hr->used() * yg_surv_rate); ysr@777: } ysr@777: ysr@777: return bytes_to_copy; ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::start_recording_regions() { ysr@777: _recorded_rs_lengths = 0; ysr@777: _recorded_scan_only_regions = 0; ysr@777: _recorded_young_regions = 0; ysr@777: _recorded_non_young_regions = 0; ysr@777: ysr@777: #if PREDICTIONS_VERBOSE ysr@777: _predicted_rs_lengths = 0; ysr@777: _predicted_cards_scanned = 0; ysr@777: ysr@777: _recorded_marked_bytes = 0; ysr@777: _recorded_young_bytes = 0; ysr@777: _predicted_bytes_to_copy = 0; ysr@777: #endif // PREDICTIONS_VERBOSE ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::record_cset_region(HeapRegion* hr, bool young) { ysr@777: if (young) { ysr@777: ++_recorded_young_regions; ysr@777: } else { ysr@777: ++_recorded_non_young_regions; ysr@777: } ysr@777: #if PREDICTIONS_VERBOSE ysr@777: if (young) { apetrusenko@980: _recorded_young_bytes += hr->used(); ysr@777: } else { ysr@777: _recorded_marked_bytes += hr->max_live_bytes(); ysr@777: } ysr@777: _predicted_bytes_to_copy += predict_bytes_to_copy(hr); ysr@777: #endif // PREDICTIONS_VERBOSE ysr@777: ysr@777: size_t rs_length = hr->rem_set()->occupied(); ysr@777: _recorded_rs_lengths += rs_length; ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::record_scan_only_regions(size_t scan_only_length) { ysr@777: _recorded_scan_only_regions = scan_only_length; ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::end_recording_regions() { ysr@777: #if PREDICTIONS_VERBOSE ysr@777: _predicted_pending_cards = predict_pending_cards(); ysr@777: _predicted_rs_lengths = _recorded_rs_lengths + predict_rs_length_diff(); ysr@777: if (full_young_gcs()) ysr@777: _predicted_cards_scanned += predict_young_card_num(_predicted_rs_lengths); ysr@777: else ysr@777: _predicted_cards_scanned += ysr@777: predict_non_young_card_num(_predicted_rs_lengths); ysr@777: _recorded_region_num = _recorded_young_regions + _recorded_non_young_regions; ysr@777: ysr@777: _predicted_scan_only_scan_time_ms = ysr@777: predict_scan_only_time_ms(_recorded_scan_only_regions); ysr@777: _predicted_rs_update_time_ms = ysr@777: predict_rs_update_time_ms(_g1->pending_card_num()); ysr@777: _predicted_rs_scan_time_ms = ysr@777: predict_rs_scan_time_ms(_predicted_cards_scanned); ysr@777: _predicted_object_copy_time_ms = ysr@777: predict_object_copy_time_ms(_predicted_bytes_to_copy); ysr@777: _predicted_constant_other_time_ms = ysr@777: predict_constant_other_time_ms(); ysr@777: _predicted_young_other_time_ms = ysr@777: predict_young_other_time_ms(_recorded_young_regions); ysr@777: _predicted_non_young_other_time_ms = ysr@777: predict_non_young_other_time_ms(_recorded_non_young_regions); ysr@777: ysr@777: _predicted_pause_time_ms = ysr@777: _predicted_scan_only_scan_time_ms + ysr@777: _predicted_rs_update_time_ms + ysr@777: _predicted_rs_scan_time_ms + ysr@777: _predicted_object_copy_time_ms + ysr@777: _predicted_constant_other_time_ms + ysr@777: _predicted_young_other_time_ms + ysr@777: _predicted_non_young_other_time_ms; ysr@777: #endif // PREDICTIONS_VERBOSE ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::check_if_region_is_too_expensive(double ysr@777: predicted_time_ms) { ysr@777: // I don't think we need to do this when in young GC mode since ysr@777: // marking will be initiated next time we hit the soft limit anyway... ysr@777: if (predicted_time_ms > _expensive_region_limit_ms) { ysr@777: if (!in_young_gc_mode()) { ysr@777: set_full_young_gcs(true); ysr@777: _should_initiate_conc_mark = true; ysr@777: } else ysr@777: // no point in doing another partial one ysr@777: _should_revert_to_full_young_gcs = true; ysr@777: } ysr@777: } ysr@777: ysr@777: // ysr@777: ysr@777: ysr@777: void G1CollectorPolicy::update_recent_gc_times(double end_time_sec, ysr@777: double elapsed_ms) { ysr@777: _recent_gc_times_ms->add(elapsed_ms); ysr@777: _recent_prev_end_times_for_all_gcs_sec->add(end_time_sec); ysr@777: _prev_collection_pause_end_ms = end_time_sec * 1000.0; ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::recent_avg_time_for_pauses_ms() { ysr@777: if (_recent_pause_times_ms->num() == 0) return (double) G1MaxPauseTimeMS; ysr@777: else return _recent_pause_times_ms->avg(); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::recent_avg_time_for_CH_strong_ms() { ysr@777: if (_recent_CH_strong_roots_times_ms->num() == 0) ysr@777: return (double)G1MaxPauseTimeMS/3.0; ysr@777: else return _recent_CH_strong_roots_times_ms->avg(); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::recent_avg_time_for_G1_strong_ms() { ysr@777: if (_recent_G1_strong_roots_times_ms->num() == 0) ysr@777: return (double)G1MaxPauseTimeMS/3.0; ysr@777: else return _recent_G1_strong_roots_times_ms->avg(); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::recent_avg_time_for_evac_ms() { ysr@777: if (_recent_evac_times_ms->num() == 0) return (double)G1MaxPauseTimeMS/3.0; ysr@777: else return _recent_evac_times_ms->avg(); ysr@777: } ysr@777: ysr@777: int G1CollectorPolicy::number_of_recent_gcs() { ysr@777: assert(_recent_CH_strong_roots_times_ms->num() == ysr@777: _recent_G1_strong_roots_times_ms->num(), "Sequence out of sync"); ysr@777: assert(_recent_G1_strong_roots_times_ms->num() == ysr@777: _recent_evac_times_ms->num(), "Sequence out of sync"); ysr@777: assert(_recent_evac_times_ms->num() == ysr@777: _recent_pause_times_ms->num(), "Sequence out of sync"); ysr@777: assert(_recent_pause_times_ms->num() == ysr@777: _recent_CS_bytes_used_before->num(), "Sequence out of sync"); ysr@777: assert(_recent_CS_bytes_used_before->num() == ysr@777: _recent_CS_bytes_surviving->num(), "Sequence out of sync"); ysr@777: return _recent_pause_times_ms->num(); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::recent_avg_survival_fraction() { ysr@777: return recent_avg_survival_fraction_work(_recent_CS_bytes_surviving, ysr@777: _recent_CS_bytes_used_before); ysr@777: } ysr@777: ysr@777: double G1CollectorPolicy::last_survival_fraction() { ysr@777: return last_survival_fraction_work(_recent_CS_bytes_surviving, ysr@777: _recent_CS_bytes_used_before); ysr@777: } ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::recent_avg_survival_fraction_work(TruncatedSeq* surviving, ysr@777: TruncatedSeq* before) { ysr@777: assert(surviving->num() == before->num(), "Sequence out of sync"); ysr@777: if (before->sum() > 0.0) { ysr@777: double recent_survival_rate = surviving->sum() / before->sum(); ysr@777: // We exempt parallel collection from this check because Alloc Buffer ysr@777: // fragmentation can produce negative collections. ysr@777: // Further, we're now always doing parallel collection. But I'm still ysr@777: // leaving this here as a placeholder for a more precise assertion later. ysr@777: // (DLD, 10/05.) ysr@777: assert((true || ParallelGCThreads > 0) || ysr@777: _g1->evacuation_failed() || ysr@777: recent_survival_rate <= 1.0, "Or bad frac"); ysr@777: return recent_survival_rate; ysr@777: } else { ysr@777: return 1.0; // Be conservative. ysr@777: } ysr@777: } ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::last_survival_fraction_work(TruncatedSeq* surviving, ysr@777: TruncatedSeq* before) { ysr@777: assert(surviving->num() == before->num(), "Sequence out of sync"); ysr@777: if (surviving->num() > 0 && before->last() > 0.0) { ysr@777: double last_survival_rate = surviving->last() / before->last(); ysr@777: // We exempt parallel collection from this check because Alloc Buffer ysr@777: // fragmentation can produce negative collections. ysr@777: // Further, we're now always doing parallel collection. But I'm still ysr@777: // leaving this here as a placeholder for a more precise assertion later. ysr@777: // (DLD, 10/05.) ysr@777: assert((true || ParallelGCThreads > 0) || ysr@777: last_survival_rate <= 1.0, "Or bad frac"); ysr@777: return last_survival_rate; ysr@777: } else { ysr@777: return 1.0; ysr@777: } ysr@777: } ysr@777: ysr@777: static const int survival_min_obs = 5; ysr@777: static double survival_min_obs_limits[] = { 0.9, 0.7, 0.5, 0.3, 0.1 }; ysr@777: static const double min_survival_rate = 0.1; ysr@777: ysr@777: double ysr@777: G1CollectorPolicy::conservative_avg_survival_fraction_work(double avg, ysr@777: double latest) { ysr@777: double res = avg; ysr@777: if (number_of_recent_gcs() < survival_min_obs) { ysr@777: res = MAX2(res, survival_min_obs_limits[number_of_recent_gcs()]); ysr@777: } ysr@777: res = MAX2(res, latest); ysr@777: res = MAX2(res, min_survival_rate); ysr@777: // In the parallel case, LAB fragmentation can produce "negative ysr@777: // collections"; so can evac failure. Cap at 1.0 ysr@777: res = MIN2(res, 1.0); ysr@777: return res; ysr@777: } ysr@777: ysr@777: size_t G1CollectorPolicy::expansion_amount() { ysr@777: if ((int)(recent_avg_pause_time_ratio() * 100.0) > G1GCPct) { ysr@777: // We will double the existing space, or take G1ExpandByPctOfAvail % of ysr@777: // the available expansion space, whichever is smaller, bounded below ysr@777: // by a minimum expansion (unless that's all that's left.) ysr@777: const size_t min_expand_bytes = 1*M; ysr@777: size_t reserved_bytes = _g1->g1_reserved_obj_bytes(); ysr@777: size_t committed_bytes = _g1->capacity(); ysr@777: size_t uncommitted_bytes = reserved_bytes - committed_bytes; ysr@777: size_t expand_bytes; ysr@777: size_t expand_bytes_via_pct = ysr@777: uncommitted_bytes * G1ExpandByPctOfAvail / 100; ysr@777: expand_bytes = MIN2(expand_bytes_via_pct, committed_bytes); ysr@777: expand_bytes = MAX2(expand_bytes, min_expand_bytes); ysr@777: expand_bytes = MIN2(expand_bytes, uncommitted_bytes); ysr@777: if (G1PolicyVerbose > 1) { ysr@777: gclog_or_tty->print("Decided to expand: ratio = %5.2f, " ysr@777: "committed = %d%s, uncommited = %d%s, via pct = %d%s.\n" ysr@777: " Answer = %d.\n", ysr@777: recent_avg_pause_time_ratio(), ysr@777: byte_size_in_proper_unit(committed_bytes), ysr@777: proper_unit_for_byte_size(committed_bytes), ysr@777: byte_size_in_proper_unit(uncommitted_bytes), ysr@777: proper_unit_for_byte_size(uncommitted_bytes), ysr@777: byte_size_in_proper_unit(expand_bytes_via_pct), ysr@777: proper_unit_for_byte_size(expand_bytes_via_pct), ysr@777: byte_size_in_proper_unit(expand_bytes), ysr@777: proper_unit_for_byte_size(expand_bytes)); ysr@777: } ysr@777: return expand_bytes; ysr@777: } else { ysr@777: return 0; ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::note_start_of_mark_thread() { ysr@777: _mark_thread_startup_sec = os::elapsedTime(); ysr@777: } ysr@777: ysr@777: class CountCSClosure: public HeapRegionClosure { ysr@777: G1CollectorPolicy* _g1_policy; ysr@777: public: ysr@777: CountCSClosure(G1CollectorPolicy* g1_policy) : ysr@777: _g1_policy(g1_policy) {} ysr@777: bool doHeapRegion(HeapRegion* r) { ysr@777: _g1_policy->_bytes_in_collection_set_before_gc += r->used(); ysr@777: return false; ysr@777: } ysr@777: }; ysr@777: ysr@777: void G1CollectorPolicy::count_CS_bytes_used() { ysr@777: CountCSClosure cs_closure(this); ysr@777: _g1->collection_set_iterate(&cs_closure); ysr@777: } ysr@777: ysr@777: static void print_indent(int level) { ysr@777: for (int j = 0; j < level+1; ++j) ysr@777: gclog_or_tty->print(" "); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_summary (int level, ysr@777: const char* str, ysr@777: NumberSeq* seq) const { ysr@777: double sum = seq->sum(); ysr@777: print_indent(level); ysr@777: gclog_or_tty->print_cr("%-24s = %8.2lf s (avg = %8.2lf ms)", ysr@777: str, sum / 1000.0, seq->avg()); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_summary_sd (int level, ysr@777: const char* str, ysr@777: NumberSeq* seq) const { ysr@777: print_summary(level, str, seq); ysr@777: print_indent(level + 5); ysr@777: gclog_or_tty->print_cr("(num = %5d, std dev = %8.2lf ms, max = %8.2lf ms)", ysr@777: seq->num(), seq->sd(), seq->maximum()); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::check_other_times(int level, ysr@777: NumberSeq* other_times_ms, ysr@777: NumberSeq* calc_other_times_ms) const { ysr@777: bool should_print = false; ysr@777: ysr@777: double max_sum = MAX2(fabs(other_times_ms->sum()), ysr@777: fabs(calc_other_times_ms->sum())); ysr@777: double min_sum = MIN2(fabs(other_times_ms->sum()), ysr@777: fabs(calc_other_times_ms->sum())); ysr@777: double sum_ratio = max_sum / min_sum; ysr@777: if (sum_ratio > 1.1) { ysr@777: should_print = true; ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## CALCULATED OTHER SUM DOESN'T MATCH RECORDED ###"); ysr@777: } ysr@777: ysr@777: double max_avg = MAX2(fabs(other_times_ms->avg()), ysr@777: fabs(calc_other_times_ms->avg())); ysr@777: double min_avg = MIN2(fabs(other_times_ms->avg()), ysr@777: fabs(calc_other_times_ms->avg())); ysr@777: double avg_ratio = max_avg / min_avg; ysr@777: if (avg_ratio > 1.1) { ysr@777: should_print = true; ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## CALCULATED OTHER AVG DOESN'T MATCH RECORDED ###"); ysr@777: } ysr@777: ysr@777: if (other_times_ms->sum() < -0.01) { ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## RECORDED OTHER SUM IS NEGATIVE ###"); ysr@777: } ysr@777: ysr@777: if (other_times_ms->avg() < -0.01) { ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## RECORDED OTHER AVG IS NEGATIVE ###"); ysr@777: } ysr@777: ysr@777: if (calc_other_times_ms->sum() < -0.01) { ysr@777: should_print = true; ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## CALCULATED OTHER SUM IS NEGATIVE ###"); ysr@777: } ysr@777: ysr@777: if (calc_other_times_ms->avg() < -0.01) { ysr@777: should_print = true; ysr@777: print_indent(level + 1); ysr@777: gclog_or_tty->print_cr("## CALCULATED OTHER AVG IS NEGATIVE ###"); ysr@777: } ysr@777: ysr@777: if (should_print) ysr@777: print_summary(level, "Other(Calc)", calc_other_times_ms); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_summary(PauseSummary* summary) const { ysr@777: bool parallel = ParallelGCThreads > 0; ysr@777: MainBodySummary* body_summary = summary->main_body_summary(); ysr@777: PopPreambleSummary* preamble_summary = summary->pop_preamble_summary(); ysr@777: ysr@777: if (summary->get_total_seq()->num() > 0) { ysr@777: print_summary_sd(0, ysr@777: (preamble_summary == NULL) ? "Non-Popular Pauses" : ysr@777: "Popular Pauses", ysr@777: summary->get_total_seq()); ysr@777: if (preamble_summary != NULL) { ysr@777: print_summary(1, "Popularity Preamble", ysr@777: preamble_summary->get_pop_preamble_seq()); ysr@777: print_summary(2, "Update RS", preamble_summary->get_pop_update_rs_seq()); ysr@777: print_summary(2, "Scan RS", preamble_summary->get_pop_scan_rs_seq()); ysr@777: print_summary(2, "Closure App", ysr@777: preamble_summary->get_pop_closure_app_seq()); ysr@777: print_summary(2, "Evacuation", ysr@777: preamble_summary->get_pop_evacuation_seq()); ysr@777: print_summary(2, "Other", preamble_summary->get_pop_other_seq()); ysr@777: { ysr@777: NumberSeq* other_parts[] = { ysr@777: preamble_summary->get_pop_update_rs_seq(), ysr@777: preamble_summary->get_pop_scan_rs_seq(), ysr@777: preamble_summary->get_pop_closure_app_seq(), ysr@777: preamble_summary->get_pop_evacuation_seq() ysr@777: }; ysr@777: NumberSeq calc_other_times_ms(preamble_summary->get_pop_preamble_seq(), ysr@777: 4, other_parts); ysr@777: check_other_times(2, preamble_summary->get_pop_other_seq(), ysr@777: &calc_other_times_ms); ysr@777: } ysr@777: } ysr@777: if (body_summary != NULL) { ysr@777: print_summary(1, "SATB Drain", body_summary->get_satb_drain_seq()); ysr@777: if (parallel) { ysr@777: print_summary(1, "Parallel Time", body_summary->get_parallel_seq()); ysr@777: print_summary(2, "Update RS", body_summary->get_update_rs_seq()); ysr@777: print_summary(2, "Ext Root Scanning", ysr@777: body_summary->get_ext_root_scan_seq()); ysr@777: print_summary(2, "Mark Stack Scanning", ysr@777: body_summary->get_mark_stack_scan_seq()); ysr@777: print_summary(2, "Scan-Only Scanning", ysr@777: body_summary->get_scan_only_seq()); ysr@777: print_summary(2, "Scan RS", body_summary->get_scan_rs_seq()); ysr@777: print_summary(2, "Object Copy", body_summary->get_obj_copy_seq()); ysr@777: print_summary(2, "Termination", body_summary->get_termination_seq()); ysr@777: print_summary(2, "Other", body_summary->get_parallel_other_seq()); ysr@777: { ysr@777: NumberSeq* other_parts[] = { ysr@777: body_summary->get_update_rs_seq(), ysr@777: body_summary->get_ext_root_scan_seq(), ysr@777: body_summary->get_mark_stack_scan_seq(), ysr@777: body_summary->get_scan_only_seq(), ysr@777: body_summary->get_scan_rs_seq(), ysr@777: body_summary->get_obj_copy_seq(), ysr@777: body_summary->get_termination_seq() ysr@777: }; ysr@777: NumberSeq calc_other_times_ms(body_summary->get_parallel_seq(), ysr@777: 7, other_parts); ysr@777: check_other_times(2, body_summary->get_parallel_other_seq(), ysr@777: &calc_other_times_ms); ysr@777: } ysr@777: print_summary(1, "Mark Closure", body_summary->get_mark_closure_seq()); ysr@777: print_summary(1, "Clear CT", body_summary->get_clear_ct_seq()); ysr@777: } else { ysr@777: print_summary(1, "Update RS", body_summary->get_update_rs_seq()); ysr@777: print_summary(1, "Ext Root Scanning", ysr@777: body_summary->get_ext_root_scan_seq()); ysr@777: print_summary(1, "Mark Stack Scanning", ysr@777: body_summary->get_mark_stack_scan_seq()); ysr@777: print_summary(1, "Scan-Only Scanning", ysr@777: body_summary->get_scan_only_seq()); ysr@777: print_summary(1, "Scan RS", body_summary->get_scan_rs_seq()); ysr@777: print_summary(1, "Object Copy", body_summary->get_obj_copy_seq()); ysr@777: } ysr@777: } ysr@777: print_summary(1, "Other", summary->get_other_seq()); ysr@777: { ysr@777: NumberSeq calc_other_times_ms; ysr@777: if (body_summary != NULL) { ysr@777: // not abandoned ysr@777: if (parallel) { ysr@777: // parallel ysr@777: NumberSeq* other_parts[] = { ysr@777: body_summary->get_satb_drain_seq(), ysr@777: (preamble_summary == NULL) ? NULL : ysr@777: preamble_summary->get_pop_preamble_seq(), ysr@777: body_summary->get_parallel_seq(), ysr@777: body_summary->get_clear_ct_seq() ysr@777: }; ysr@777: calc_other_times_ms = NumberSeq (summary->get_total_seq(), ysr@777: 4, other_parts); ysr@777: } else { ysr@777: // serial ysr@777: NumberSeq* other_parts[] = { ysr@777: body_summary->get_satb_drain_seq(), ysr@777: (preamble_summary == NULL) ? NULL : ysr@777: preamble_summary->get_pop_preamble_seq(), ysr@777: body_summary->get_update_rs_seq(), ysr@777: body_summary->get_ext_root_scan_seq(), ysr@777: body_summary->get_mark_stack_scan_seq(), ysr@777: body_summary->get_scan_only_seq(), ysr@777: body_summary->get_scan_rs_seq(), ysr@777: body_summary->get_obj_copy_seq() ysr@777: }; ysr@777: calc_other_times_ms = NumberSeq(summary->get_total_seq(), ysr@777: 8, other_parts); ysr@777: } ysr@777: } else { ysr@777: // abandoned ysr@777: NumberSeq* other_parts[] = { ysr@777: (preamble_summary == NULL) ? NULL : ysr@777: preamble_summary->get_pop_preamble_seq() ysr@777: }; ysr@777: calc_other_times_ms = NumberSeq(summary->get_total_seq(), ysr@777: 1, other_parts); ysr@777: } ysr@777: check_other_times(1, summary->get_other_seq(), &calc_other_times_ms); ysr@777: } ysr@777: } else { ysr@777: print_indent(0); ysr@777: gclog_or_tty->print_cr("none"); ysr@777: } ysr@777: gclog_or_tty->print_cr(""); ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::print_abandoned_summary(PauseSummary* non_pop_summary, ysr@777: PauseSummary* pop_summary) const { ysr@777: bool printed = false; ysr@777: if (non_pop_summary->get_total_seq()->num() > 0) { ysr@777: printed = true; ysr@777: print_summary(non_pop_summary); ysr@777: } ysr@777: if (pop_summary->get_total_seq()->num() > 0) { ysr@777: printed = true; ysr@777: print_summary(pop_summary); ysr@777: } ysr@777: ysr@777: if (!printed) { ysr@777: print_indent(0); ysr@777: gclog_or_tty->print_cr("none"); ysr@777: gclog_or_tty->print_cr(""); ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_tracing_info() const { ysr@777: if (TraceGen0Time) { ysr@777: gclog_or_tty->print_cr("ALL PAUSES"); ysr@777: print_summary_sd(0, "Total", _all_pause_times_ms); ysr@777: gclog_or_tty->print_cr(""); ysr@777: gclog_or_tty->print_cr(""); ysr@777: gclog_or_tty->print_cr(" Full Young GC Pauses: %8d", _full_young_pause_num); ysr@777: gclog_or_tty->print_cr(" Partial Young GC Pauses: %8d", _partial_young_pause_num); ysr@777: gclog_or_tty->print_cr(""); ysr@777: ysr@777: gclog_or_tty->print_cr("NON-POPULAR PAUSES"); ysr@777: print_summary(_non_pop_summary); ysr@777: ysr@777: gclog_or_tty->print_cr("POPULAR PAUSES"); ysr@777: print_summary(_pop_summary); ysr@777: ysr@777: gclog_or_tty->print_cr("ABANDONED PAUSES"); ysr@777: print_abandoned_summary(_non_pop_abandoned_summary, ysr@777: _pop_abandoned_summary); ysr@777: ysr@777: gclog_or_tty->print_cr("MISC"); ysr@777: print_summary_sd(0, "Stop World", _all_stop_world_times_ms); ysr@777: print_summary_sd(0, "Yields", _all_yield_times_ms); ysr@777: for (int i = 0; i < _aux_num; ++i) { ysr@777: if (_all_aux_times_ms[i].num() > 0) { ysr@777: char buffer[96]; ysr@777: sprintf(buffer, "Aux%d", i); ysr@777: print_summary_sd(0, buffer, &_all_aux_times_ms[i]); ysr@777: } ysr@777: } ysr@777: ysr@777: size_t all_region_num = _region_num_young + _region_num_tenured; ysr@777: gclog_or_tty->print_cr(" New Regions %8d, Young %8d (%6.2lf%%), " ysr@777: "Tenured %8d (%6.2lf%%)", ysr@777: all_region_num, ysr@777: _region_num_young, ysr@777: (double) _region_num_young / (double) all_region_num * 100.0, ysr@777: _region_num_tenured, ysr@777: (double) _region_num_tenured / (double) all_region_num * 100.0); ysr@777: ysr@777: if (!G1RSBarrierUseQueue) { ysr@777: gclog_or_tty->print_cr("Of %d times conc refinement was enabled, %d (%7.2f%%) " ysr@777: "did zero traversals.", ysr@777: _conc_refine_enabled, _conc_refine_zero_traversals, ysr@777: _conc_refine_enabled > 0 ? ysr@777: 100.0 * (float)_conc_refine_zero_traversals/ ysr@777: (float)_conc_refine_enabled : 0.0); ysr@777: gclog_or_tty->print_cr(" Max # of traversals = %d.", ysr@777: _conc_refine_max_traversals); ysr@777: gclog_or_tty->print_cr(""); ysr@777: } ysr@777: } ysr@777: if (TraceGen1Time) { ysr@777: if (_all_full_gc_times_ms->num() > 0) { ysr@777: gclog_or_tty->print("\n%4d full_gcs: total time = %8.2f s", ysr@777: _all_full_gc_times_ms->num(), ysr@777: _all_full_gc_times_ms->sum() / 1000.0); ysr@777: gclog_or_tty->print_cr(" (avg = %8.2fms).", _all_full_gc_times_ms->avg()); ysr@777: gclog_or_tty->print_cr(" [std. dev = %8.2f ms, max = %8.2f ms]", ysr@777: _all_full_gc_times_ms->sd(), ysr@777: _all_full_gc_times_ms->maximum()); ysr@777: } ysr@777: } ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::print_yg_surv_rate_info() const { ysr@777: #ifndef PRODUCT ysr@777: _short_lived_surv_rate_group->print_surv_rate_summary(); ysr@777: // add this call for any other surv rate groups ysr@777: #endif // PRODUCT ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::update_conc_refine_data() { ysr@777: unsigned traversals = _g1->concurrent_g1_refine()->disable(); ysr@777: if (traversals == 0) _conc_refine_zero_traversals++; ysr@777: _conc_refine_max_traversals = MAX2(_conc_refine_max_traversals, ysr@777: (size_t)traversals); ysr@777: ysr@777: if (G1PolicyVerbose > 1) ysr@777: gclog_or_tty->print_cr("Did a CR traversal series: %d traversals.", traversals); ysr@777: double multiplier = 1.0; ysr@777: if (traversals == 0) { ysr@777: multiplier = 4.0; ysr@777: } else if (traversals > (size_t)G1ConcRefineTargTraversals) { ysr@777: multiplier = 1.0/1.5; ysr@777: } else if (traversals < (size_t)G1ConcRefineTargTraversals) { ysr@777: multiplier = 1.5; ysr@777: } ysr@777: if (G1PolicyVerbose > 1) { ysr@777: gclog_or_tty->print_cr(" Multiplier = %7.2f.", multiplier); ysr@777: gclog_or_tty->print(" Delta went from %d regions to ", ysr@777: _conc_refine_current_delta); ysr@777: } ysr@777: _conc_refine_current_delta = ysr@777: MIN2(_g1->n_regions(), ysr@777: (size_t)(_conc_refine_current_delta * multiplier)); ysr@777: _conc_refine_current_delta = ysr@777: MAX2(_conc_refine_current_delta, (size_t)1); ysr@777: if (G1PolicyVerbose > 1) { ysr@777: gclog_or_tty->print_cr("%d regions.", _conc_refine_current_delta); ysr@777: } ysr@777: _conc_refine_enabled++; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy::set_single_region_collection_set(HeapRegion* hr) { ysr@777: assert(collection_set() == NULL, "Must be no current CS."); ysr@777: _collection_set_size = 0; ysr@777: _collection_set_bytes_used_before = 0; ysr@777: add_to_collection_set(hr); ysr@777: count_CS_bytes_used(); ysr@777: } ysr@777: ysr@777: bool ysr@777: G1CollectorPolicy::should_add_next_region_to_young_list() { ysr@777: assert(in_young_gc_mode(), "should be in young GC mode"); ysr@777: bool ret; ysr@777: size_t young_list_length = _g1->young_list_length(); apetrusenko@980: size_t young_list_max_length = _young_list_target_length; apetrusenko@980: if (G1FixedEdenSize) { apetrusenko@980: young_list_max_length -= _max_survivor_regions; apetrusenko@980: } apetrusenko@980: if (young_list_length < young_list_max_length) { ysr@777: ret = true; ysr@777: ++_region_num_young; ysr@777: } else { ysr@777: ret = false; ysr@777: ++_region_num_tenured; ysr@777: } ysr@777: ysr@777: return ret; ysr@777: } ysr@777: ysr@777: #ifndef PRODUCT ysr@777: // for debugging, bit of a hack... ysr@777: static char* ysr@777: region_num_to_mbs(int length) { ysr@777: static char buffer[64]; ysr@777: double bytes = (double) (length * HeapRegion::GrainBytes); ysr@777: double mbs = bytes / (double) (1024 * 1024); ysr@777: sprintf(buffer, "%7.2lfMB", mbs); ysr@777: return buffer; ysr@777: } ysr@777: #endif // PRODUCT ysr@777: ysr@777: void ysr@777: G1CollectorPolicy::checkpoint_conc_overhead() { ysr@777: double conc_overhead = 0.0; ysr@777: if (G1AccountConcurrentOverhead) ysr@777: conc_overhead = COTracker::totalPredConcOverhead(); ysr@777: _mmu_tracker->update_conc_overhead(conc_overhead); ysr@777: #if 0 ysr@777: gclog_or_tty->print(" CO %1.4lf TARGET %1.4lf", ysr@777: conc_overhead, _mmu_tracker->max_gc_time()); ysr@777: #endif ysr@777: } ysr@777: ysr@777: apetrusenko@980: size_t G1CollectorPolicy::max_regions(int purpose) { ysr@777: switch (purpose) { ysr@777: case GCAllocForSurvived: apetrusenko@980: return _max_survivor_regions; ysr@777: case GCAllocForTenured: apetrusenko@980: return REGIONS_UNLIMITED; ysr@777: default: apetrusenko@980: ShouldNotReachHere(); apetrusenko@980: return REGIONS_UNLIMITED; ysr@777: }; ysr@777: } ysr@777: apetrusenko@980: // Calculates survivor space parameters. apetrusenko@980: void G1CollectorPolicy::calculate_survivors_policy() apetrusenko@980: { apetrusenko@980: if (!G1UseSurvivorSpace) { apetrusenko@980: return; apetrusenko@980: } apetrusenko@980: if (G1FixedSurvivorSpaceSize == 0) { apetrusenko@980: _max_survivor_regions = _young_list_target_length / SurvivorRatio; apetrusenko@980: } else { apetrusenko@982: _max_survivor_regions = G1FixedSurvivorSpaceSize / HeapRegion::GrainBytes; apetrusenko@980: } apetrusenko@980: apetrusenko@980: if (G1FixedTenuringThreshold) { apetrusenko@980: _tenuring_threshold = MaxTenuringThreshold; apetrusenko@980: } else { apetrusenko@980: _tenuring_threshold = _survivors_age_table.compute_tenuring_threshold( apetrusenko@980: HeapRegion::GrainWords * _max_survivor_regions); apetrusenko@980: } apetrusenko@980: } apetrusenko@980: apetrusenko@980: ysr@777: void ysr@777: G1CollectorPolicy_BestRegionsFirst:: ysr@777: set_single_region_collection_set(HeapRegion* hr) { ysr@777: G1CollectorPolicy::set_single_region_collection_set(hr); ysr@777: _collectionSetChooser->removeRegion(hr); ysr@777: } ysr@777: ysr@777: ysr@777: bool ysr@777: G1CollectorPolicy_BestRegionsFirst::should_do_collection_pause(size_t ysr@777: word_size) { ysr@777: assert(_g1->regions_accounted_for(), "Region leakage!"); ysr@777: // Initiate a pause when we reach the steady-state "used" target. ysr@777: size_t used_hard = (_g1->capacity() / 100) * G1SteadyStateUsed; ysr@777: size_t used_soft = ysr@777: MAX2((_g1->capacity() / 100) * (G1SteadyStateUsed - G1SteadyStateUsedDelta), ysr@777: used_hard/2); ysr@777: size_t used = _g1->used(); ysr@777: ysr@777: double max_pause_time_ms = _mmu_tracker->max_gc_time() * 1000.0; ysr@777: ysr@777: size_t young_list_length = _g1->young_list_length(); apetrusenko@980: size_t young_list_max_length = _young_list_target_length; apetrusenko@980: if (G1FixedEdenSize) { apetrusenko@980: young_list_max_length -= _max_survivor_regions; apetrusenko@980: } apetrusenko@980: bool reached_target_length = young_list_length >= young_list_max_length; ysr@777: ysr@777: if (in_young_gc_mode()) { ysr@777: if (reached_target_length) { ysr@777: assert( young_list_length > 0 && _g1->young_list_length() > 0, ysr@777: "invariant" ); ysr@777: _target_pause_time_ms = max_pause_time_ms; ysr@777: return true; ysr@777: } ysr@777: } else { ysr@777: guarantee( false, "should not reach here" ); ysr@777: } ysr@777: ysr@777: return false; ysr@777: } ysr@777: ysr@777: #ifndef PRODUCT ysr@777: class HRSortIndexIsOKClosure: public HeapRegionClosure { ysr@777: CollectionSetChooser* _chooser; ysr@777: public: ysr@777: HRSortIndexIsOKClosure(CollectionSetChooser* chooser) : ysr@777: _chooser(chooser) {} ysr@777: ysr@777: bool doHeapRegion(HeapRegion* r) { ysr@777: if (!r->continuesHumongous()) { ysr@777: assert(_chooser->regionProperlyOrdered(r), "Ought to be."); ysr@777: } ysr@777: return false; ysr@777: } ysr@777: }; ysr@777: ysr@777: bool G1CollectorPolicy_BestRegionsFirst::assertMarkedBytesDataOK() { ysr@777: HRSortIndexIsOKClosure cl(_collectionSetChooser); ysr@777: _g1->heap_region_iterate(&cl); ysr@777: return true; ysr@777: } ysr@777: #endif ysr@777: ysr@777: void ysr@777: G1CollectorPolicy_BestRegionsFirst:: ysr@777: record_collection_pause_start(double start_time_sec, size_t start_used) { ysr@777: G1CollectorPolicy::record_collection_pause_start(start_time_sec, start_used); ysr@777: } ysr@777: ysr@777: class NextNonCSElemFinder: public HeapRegionClosure { ysr@777: HeapRegion* _res; ysr@777: public: ysr@777: NextNonCSElemFinder(): _res(NULL) {} ysr@777: bool doHeapRegion(HeapRegion* r) { ysr@777: if (!r->in_collection_set()) { ysr@777: _res = r; ysr@777: return true; ysr@777: } else { ysr@777: return false; ysr@777: } ysr@777: } ysr@777: HeapRegion* res() { return _res; } ysr@777: }; ysr@777: ysr@777: class KnownGarbageClosure: public HeapRegionClosure { ysr@777: CollectionSetChooser* _hrSorted; ysr@777: ysr@777: public: ysr@777: KnownGarbageClosure(CollectionSetChooser* hrSorted) : ysr@777: _hrSorted(hrSorted) ysr@777: {} ysr@777: ysr@777: bool doHeapRegion(HeapRegion* r) { ysr@777: // We only include humongous regions in collection ysr@777: // sets when concurrent mark shows that their contained object is ysr@777: // unreachable. ysr@777: ysr@777: // Do we have any marking information for this region? ysr@777: if (r->is_marked()) { ysr@777: // We don't include humongous regions in collection ysr@777: // sets because we collect them immediately at the end of a marking ysr@777: // cycle. We also don't include young regions because we *must* ysr@777: // include them in the next collection pause. ysr@777: if (!r->isHumongous() && !r->is_young()) { ysr@777: _hrSorted->addMarkedHeapRegion(r); ysr@777: } ysr@777: } ysr@777: return false; ysr@777: } ysr@777: }; ysr@777: ysr@777: class ParKnownGarbageHRClosure: public HeapRegionClosure { ysr@777: CollectionSetChooser* _hrSorted; ysr@777: jint _marked_regions_added; ysr@777: jint _chunk_size; ysr@777: jint _cur_chunk_idx; ysr@777: jint _cur_chunk_end; // Cur chunk [_cur_chunk_idx, _cur_chunk_end) ysr@777: int _worker; ysr@777: int _invokes; ysr@777: ysr@777: void get_new_chunk() { ysr@777: _cur_chunk_idx = _hrSorted->getParMarkedHeapRegionChunk(_chunk_size); ysr@777: _cur_chunk_end = _cur_chunk_idx + _chunk_size; ysr@777: } ysr@777: void add_region(HeapRegion* r) { ysr@777: if (_cur_chunk_idx == _cur_chunk_end) { ysr@777: get_new_chunk(); ysr@777: } ysr@777: assert(_cur_chunk_idx < _cur_chunk_end, "postcondition"); ysr@777: _hrSorted->setMarkedHeapRegion(_cur_chunk_idx, r); ysr@777: _marked_regions_added++; ysr@777: _cur_chunk_idx++; ysr@777: } ysr@777: ysr@777: public: ysr@777: ParKnownGarbageHRClosure(CollectionSetChooser* hrSorted, ysr@777: jint chunk_size, ysr@777: int worker) : ysr@777: _hrSorted(hrSorted), _chunk_size(chunk_size), _worker(worker), ysr@777: _marked_regions_added(0), _cur_chunk_idx(0), _cur_chunk_end(0), ysr@777: _invokes(0) ysr@777: {} ysr@777: ysr@777: bool doHeapRegion(HeapRegion* r) { ysr@777: // We only include humongous regions in collection ysr@777: // sets when concurrent mark shows that their contained object is ysr@777: // unreachable. ysr@777: _invokes++; ysr@777: ysr@777: // Do we have any marking information for this region? ysr@777: if (r->is_marked()) { ysr@777: // We don't include humongous regions in collection ysr@777: // sets because we collect them immediately at the end of a marking ysr@777: // cycle. ysr@777: // We also do not include young regions in collection sets ysr@777: if (!r->isHumongous() && !r->is_young()) { ysr@777: add_region(r); ysr@777: } ysr@777: } ysr@777: return false; ysr@777: } ysr@777: jint marked_regions_added() { return _marked_regions_added; } ysr@777: int invokes() { return _invokes; } ysr@777: }; ysr@777: ysr@777: class ParKnownGarbageTask: public AbstractGangTask { ysr@777: CollectionSetChooser* _hrSorted; ysr@777: jint _chunk_size; ysr@777: G1CollectedHeap* _g1; ysr@777: public: ysr@777: ParKnownGarbageTask(CollectionSetChooser* hrSorted, jint chunk_size) : ysr@777: AbstractGangTask("ParKnownGarbageTask"), ysr@777: _hrSorted(hrSorted), _chunk_size(chunk_size), ysr@777: _g1(G1CollectedHeap::heap()) ysr@777: {} ysr@777: ysr@777: void work(int i) { ysr@777: ParKnownGarbageHRClosure parKnownGarbageCl(_hrSorted, _chunk_size, i); ysr@777: // Back to zero for the claim value. tonyp@790: _g1->heap_region_par_iterate_chunked(&parKnownGarbageCl, i, tonyp@790: HeapRegion::InitialClaimValue); ysr@777: jint regions_added = parKnownGarbageCl.marked_regions_added(); ysr@777: _hrSorted->incNumMarkedHeapRegions(regions_added); ysr@777: if (G1PrintParCleanupStats) { ysr@777: gclog_or_tty->print(" Thread %d called %d times, added %d regions to list.\n", ysr@777: i, parKnownGarbageCl.invokes(), regions_added); ysr@777: } ysr@777: } ysr@777: }; ysr@777: ysr@777: void ysr@777: G1CollectorPolicy_BestRegionsFirst:: ysr@777: record_concurrent_mark_cleanup_end(size_t freed_bytes, ysr@777: size_t max_live_bytes) { ysr@777: double start; ysr@777: if (G1PrintParCleanupStats) start = os::elapsedTime(); ysr@777: record_concurrent_mark_cleanup_end_work1(freed_bytes, max_live_bytes); ysr@777: ysr@777: _collectionSetChooser->clearMarkedHeapRegions(); ysr@777: double clear_marked_end; ysr@777: if (G1PrintParCleanupStats) { ysr@777: clear_marked_end = os::elapsedTime(); ysr@777: gclog_or_tty->print_cr(" clear marked regions + work1: %8.3f ms.", ysr@777: (clear_marked_end - start)*1000.0); ysr@777: } ysr@777: if (ParallelGCThreads > 0) { ysr@777: const size_t OverpartitionFactor = 4; ysr@777: const size_t MinChunkSize = 8; ysr@777: const size_t ChunkSize = ysr@777: MAX2(_g1->n_regions() / (ParallelGCThreads * OverpartitionFactor), ysr@777: MinChunkSize); ysr@777: _collectionSetChooser->prepareForAddMarkedHeapRegionsPar(_g1->n_regions(), ysr@777: ChunkSize); ysr@777: ParKnownGarbageTask parKnownGarbageTask(_collectionSetChooser, ysr@777: (int) ChunkSize); ysr@777: _g1->workers()->run_task(&parKnownGarbageTask); tonyp@790: tonyp@790: assert(_g1->check_heap_region_claim_values(HeapRegion::InitialClaimValue), tonyp@790: "sanity check"); ysr@777: } else { ysr@777: KnownGarbageClosure knownGarbagecl(_collectionSetChooser); ysr@777: _g1->heap_region_iterate(&knownGarbagecl); ysr@777: } ysr@777: double known_garbage_end; ysr@777: if (G1PrintParCleanupStats) { ysr@777: known_garbage_end = os::elapsedTime(); ysr@777: gclog_or_tty->print_cr(" compute known garbage: %8.3f ms.", ysr@777: (known_garbage_end - clear_marked_end)*1000.0); ysr@777: } ysr@777: _collectionSetChooser->sortMarkedHeapRegions(); ysr@777: double sort_end; ysr@777: if (G1PrintParCleanupStats) { ysr@777: sort_end = os::elapsedTime(); ysr@777: gclog_or_tty->print_cr(" sorting: %8.3f ms.", ysr@777: (sort_end - known_garbage_end)*1000.0); ysr@777: } ysr@777: ysr@777: record_concurrent_mark_cleanup_end_work2(); ysr@777: double work2_end; ysr@777: if (G1PrintParCleanupStats) { ysr@777: work2_end = os::elapsedTime(); ysr@777: gclog_or_tty->print_cr(" work2: %8.3f ms.", ysr@777: (work2_end - sort_end)*1000.0); ysr@777: } ysr@777: } ysr@777: ysr@777: // Add the heap region to the collection set and return the conservative ysr@777: // estimate of the number of live bytes. ysr@777: void G1CollectorPolicy:: ysr@777: add_to_collection_set(HeapRegion* hr) { ysr@777: if (G1TraceRegions) { ysr@777: gclog_or_tty->print_cr("added region to cset %d:["PTR_FORMAT", "PTR_FORMAT"], " ysr@777: "top "PTR_FORMAT", young %s", ysr@777: hr->hrs_index(), hr->bottom(), hr->end(), ysr@777: hr->top(), (hr->is_young()) ? "YES" : "NO"); ysr@777: } ysr@777: ysr@777: if (_g1->mark_in_progress()) ysr@777: _g1->concurrent_mark()->registerCSetRegion(hr); ysr@777: ysr@777: assert(!hr->in_collection_set(), ysr@777: "should not already be in the CSet"); ysr@777: hr->set_in_collection_set(true); ysr@777: hr->set_next_in_collection_set(_collection_set); ysr@777: _collection_set = hr; ysr@777: _collection_set_size++; ysr@777: _collection_set_bytes_used_before += hr->used(); tonyp@961: _g1->register_region_with_in_cset_fast_test(hr); ysr@777: } ysr@777: ysr@777: void ysr@777: G1CollectorPolicy_BestRegionsFirst:: ysr@777: choose_collection_set(HeapRegion* pop_region) { ysr@777: double non_young_start_time_sec; ysr@777: start_recording_regions(); ysr@777: ysr@777: if (pop_region != NULL) { ysr@777: _target_pause_time_ms = (double) G1MaxPauseTimeMS; ysr@777: } else { ysr@777: guarantee(_target_pause_time_ms > -1.0, ysr@777: "_target_pause_time_ms should have been set!"); ysr@777: } ysr@777: ysr@777: // pop region is either null (and so is CS), or else it *is* the CS. ysr@777: assert(_collection_set == pop_region, "Precondition"); ysr@777: ysr@777: double base_time_ms = predict_base_elapsed_time_ms(_pending_cards); ysr@777: double predicted_pause_time_ms = base_time_ms; ysr@777: ysr@777: double target_time_ms = _target_pause_time_ms; ysr@777: double time_remaining_ms = target_time_ms - base_time_ms; ysr@777: ysr@777: // the 10% and 50% values are arbitrary... ysr@777: if (time_remaining_ms < 0.10*target_time_ms) { ysr@777: time_remaining_ms = 0.50 * target_time_ms; ysr@777: _within_target = false; ysr@777: } else { ysr@777: _within_target = true; ysr@777: } ysr@777: ysr@777: // We figure out the number of bytes available for future to-space. ysr@777: // For new regions without marking information, we must assume the ysr@777: // worst-case of complete survival. If we have marking information for a ysr@777: // region, we can bound the amount of live data. We can add a number of ysr@777: // such regions, as long as the sum of the live data bounds does not ysr@777: // exceed the available evacuation space. ysr@777: size_t max_live_bytes = _g1->free_regions() * HeapRegion::GrainBytes; ysr@777: ysr@777: size_t expansion_bytes = ysr@777: _g1->expansion_regions() * HeapRegion::GrainBytes; ysr@777: ysr@777: if (pop_region == NULL) { ysr@777: _collection_set_bytes_used_before = 0; ysr@777: _collection_set_size = 0; ysr@777: } ysr@777: ysr@777: // Adjust for expansion and slop. ysr@777: max_live_bytes = max_live_bytes + expansion_bytes; ysr@777: ysr@777: assert(pop_region != NULL || _g1->regions_accounted_for(), "Region leakage!"); ysr@777: ysr@777: HeapRegion* hr; ysr@777: if (in_young_gc_mode()) { ysr@777: double young_start_time_sec = os::elapsedTime(); ysr@777: ysr@777: if (G1PolicyVerbose > 0) { ysr@777: gclog_or_tty->print_cr("Adding %d young regions to the CSet", ysr@777: _g1->young_list_length()); ysr@777: } ysr@777: _young_cset_length = 0; ysr@777: _last_young_gc_full = full_young_gcs() ? true : false; ysr@777: if (_last_young_gc_full) ysr@777: ++_full_young_pause_num; ysr@777: else ysr@777: ++_partial_young_pause_num; ysr@777: hr = _g1->pop_region_from_young_list(); ysr@777: while (hr != NULL) { ysr@777: ysr@777: assert( hr->young_index_in_cset() == -1, "invariant" ); ysr@777: assert( hr->age_in_surv_rate_group() != -1, "invariant" ); ysr@777: hr->set_young_index_in_cset((int) _young_cset_length); ysr@777: ysr@777: ++_young_cset_length; ysr@777: double predicted_time_ms = predict_region_elapsed_time_ms(hr, true); ysr@777: time_remaining_ms -= predicted_time_ms; ysr@777: predicted_pause_time_ms += predicted_time_ms; ysr@777: if (hr == pop_region) { ysr@777: // The popular region was young. Skip over it. ysr@777: assert(hr->in_collection_set(), "It's the pop region."); ysr@777: } else { ysr@777: assert(!hr->in_collection_set(), "It's not the pop region."); ysr@777: add_to_collection_set(hr); ysr@777: record_cset_region(hr, true); ysr@777: } ysr@777: max_live_bytes -= MIN2(hr->max_live_bytes(), max_live_bytes); ysr@777: if (G1PolicyVerbose > 0) { ysr@777: gclog_or_tty->print_cr(" Added [" PTR_FORMAT ", " PTR_FORMAT") to CS.", ysr@777: hr->bottom(), hr->end()); ysr@777: gclog_or_tty->print_cr(" (" SIZE_FORMAT " KB left in heap.)", ysr@777: max_live_bytes/K); ysr@777: } ysr@777: hr = _g1->pop_region_from_young_list(); ysr@777: } ysr@777: ysr@777: record_scan_only_regions(_g1->young_list_scan_only_length()); ysr@777: ysr@777: double young_end_time_sec = os::elapsedTime(); ysr@777: _recorded_young_cset_choice_time_ms = ysr@777: (young_end_time_sec - young_start_time_sec) * 1000.0; ysr@777: ysr@777: non_young_start_time_sec = os::elapsedTime(); ysr@777: ysr@777: if (_young_cset_length > 0 && _last_young_gc_full) { ysr@777: // don't bother adding more regions... ysr@777: goto choose_collection_set_end; ysr@777: } ysr@777: } else if (pop_region != NULL) { ysr@777: // We're not in young mode, and we chose a popular region; don't choose ysr@777: // any more. ysr@777: return; ysr@777: } ysr@777: ysr@777: if (!in_young_gc_mode() || !full_young_gcs()) { ysr@777: bool should_continue = true; ysr@777: NumberSeq seq; ysr@777: double avg_prediction = 100000000000000000.0; // something very large ysr@777: do { ysr@777: hr = _collectionSetChooser->getNextMarkedRegion(time_remaining_ms, ysr@777: avg_prediction); ysr@777: if (hr != NULL && !hr->popular()) { ysr@777: double predicted_time_ms = predict_region_elapsed_time_ms(hr, false); ysr@777: time_remaining_ms -= predicted_time_ms; ysr@777: predicted_pause_time_ms += predicted_time_ms; ysr@777: add_to_collection_set(hr); ysr@777: record_cset_region(hr, false); ysr@777: max_live_bytes -= MIN2(hr->max_live_bytes(), max_live_bytes); ysr@777: if (G1PolicyVerbose > 0) { ysr@777: gclog_or_tty->print_cr(" (" SIZE_FORMAT " KB left in heap.)", ysr@777: max_live_bytes/K); ysr@777: } ysr@777: seq.add(predicted_time_ms); ysr@777: avg_prediction = seq.avg() + seq.sd(); ysr@777: } ysr@777: should_continue = ysr@777: ( hr != NULL) && ysr@777: ( (adaptive_young_list_length()) ? time_remaining_ms > 0.0 ysr@777: : _collection_set_size < _young_list_fixed_length ); ysr@777: } while (should_continue); ysr@777: ysr@777: if (!adaptive_young_list_length() && ysr@777: _collection_set_size < _young_list_fixed_length) ysr@777: _should_revert_to_full_young_gcs = true; ysr@777: } ysr@777: ysr@777: choose_collection_set_end: ysr@777: count_CS_bytes_used(); ysr@777: ysr@777: end_recording_regions(); ysr@777: ysr@777: double non_young_end_time_sec = os::elapsedTime(); ysr@777: _recorded_non_young_cset_choice_time_ms = ysr@777: (non_young_end_time_sec - non_young_start_time_sec) * 1000.0; ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy_BestRegionsFirst::record_full_collection_end() { ysr@777: G1CollectorPolicy::record_full_collection_end(); ysr@777: _collectionSetChooser->updateAfterFullCollection(); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy_BestRegionsFirst:: ysr@777: expand_if_possible(size_t numRegions) { ysr@777: size_t expansion_bytes = numRegions * HeapRegion::GrainBytes; ysr@777: _g1->expand(expansion_bytes); ysr@777: } ysr@777: ysr@777: void G1CollectorPolicy_BestRegionsFirst:: ysr@777: record_collection_pause_end(bool popular, bool abandoned) { ysr@777: G1CollectorPolicy::record_collection_pause_end(popular, abandoned); ysr@777: assert(assertMarkedBytesDataOK(), "Marked regions not OK at pause end."); ysr@777: } ysr@777: ysr@777: // Local Variables: *** ysr@777: // c-indentation-style: gnu *** ysr@777: // End: ***