| 1 | /* | 
|---|
| 2 | * Copyright (c) 2017, 2019, Red Hat, Inc. All rights reserved. | 
|---|
| 3 | * | 
|---|
| 4 | * This code is free software; you can redistribute it and/or modify it | 
|---|
| 5 | * under the terms of the GNU General Public License version 2 only, as | 
|---|
| 6 | * published by the Free Software Foundation. | 
|---|
| 7 | * | 
|---|
| 8 | * This code is distributed in the hope that it will be useful, but WITHOUT | 
|---|
| 9 | * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or | 
|---|
| 10 | * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License | 
|---|
| 11 | * version 2 for more details (a copy is included in the LICENSE file that | 
|---|
| 12 | * accompanied this code). | 
|---|
| 13 | * | 
|---|
| 14 | * You should have received a copy of the GNU General Public License version | 
|---|
| 15 | * 2 along with this work; if not, write to the Free Software Foundation, | 
|---|
| 16 | * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. | 
|---|
| 17 | * | 
|---|
| 18 | * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA | 
|---|
| 19 | * or visit www.oracle.com if you need additional information or have any | 
|---|
| 20 | * questions. | 
|---|
| 21 | * | 
|---|
| 22 | */ | 
|---|
| 23 |  | 
|---|
| 24 | #include "precompiled.hpp" | 
|---|
| 25 |  | 
|---|
| 26 | #include "jfr/jfrEvents.hpp" | 
|---|
| 27 | #include "gc/shared/gcCause.hpp" | 
|---|
| 28 | #include "gc/shared/gcTimer.hpp" | 
|---|
| 29 | #include "gc/shared/gcTrace.hpp" | 
|---|
| 30 | #include "gc/shared/gcWhen.hpp" | 
|---|
| 31 | #include "gc/shenandoah/shenandoahAllocTracker.hpp" | 
|---|
| 32 | #include "gc/shenandoah/shenandoahCollectorPolicy.hpp" | 
|---|
| 33 | #include "gc/shenandoah/shenandoahMarkCompact.hpp" | 
|---|
| 34 | #include "gc/shenandoah/shenandoahHeap.hpp" | 
|---|
| 35 | #include "gc/shenandoah/shenandoahHeuristics.hpp" | 
|---|
| 36 | #include "gc/shenandoah/shenandoahUtils.hpp" | 
|---|
| 37 |  | 
|---|
| 38 | ShenandoahPhaseTimings::Phase ShenandoahGCPhase::_current_phase = ShenandoahGCPhase::_invalid_phase; | 
|---|
| 39 |  | 
|---|
| 40 | ShenandoahGCSession::ShenandoahGCSession(GCCause::Cause cause) : | 
|---|
| 41 | _heap(ShenandoahHeap::heap()), | 
|---|
| 42 | _timer(_heap->gc_timer()), | 
|---|
| 43 | _tracer(_heap->tracer()) { | 
|---|
| 44 | assert(!ShenandoahGCPhase::is_valid_phase(ShenandoahGCPhase::current_phase()), | 
|---|
| 45 | "No current GC phase"); | 
|---|
| 46 |  | 
|---|
| 47 | _heap->set_gc_cause(cause); | 
|---|
| 48 | _timer->register_gc_start(); | 
|---|
| 49 | _tracer->report_gc_start(cause, _timer->gc_start()); | 
|---|
| 50 | _heap->trace_heap(GCWhen::BeforeGC, _tracer); | 
|---|
| 51 |  | 
|---|
| 52 | _heap->shenandoah_policy()->record_cycle_start(); | 
|---|
| 53 | _heap->heuristics()->record_cycle_start(); | 
|---|
| 54 | _trace_cycle.initialize(_heap->cycle_memory_manager(), cause, | 
|---|
| 55 | /* allMemoryPoolsAffected */    true, | 
|---|
| 56 | /* recordGCBeginTime = */       true, | 
|---|
| 57 | /* recordPreGCUsage = */        true, | 
|---|
| 58 | /* recordPeakUsage = */         true, | 
|---|
| 59 | /* recordPostGCUsage = */       true, | 
|---|
| 60 | /* recordAccumulatedGCTime = */ true, | 
|---|
| 61 | /* recordGCEndTime = */         true, | 
|---|
| 62 | /* countCollection = */         true | 
|---|
| 63 | ); | 
|---|
| 64 | } | 
|---|
| 65 |  | 
|---|
| 66 | ShenandoahGCSession::~ShenandoahGCSession() { | 
|---|
| 67 | _heap->heuristics()->record_cycle_end(); | 
|---|
| 68 | _timer->register_gc_end(); | 
|---|
| 69 | _heap->trace_heap(GCWhen::AfterGC, _tracer); | 
|---|
| 70 | _tracer->report_gc_end(_timer->gc_end(), _timer->time_partitions()); | 
|---|
| 71 | assert(!ShenandoahGCPhase::is_valid_phase(ShenandoahGCPhase::current_phase()), | 
|---|
| 72 | "No current GC phase"); | 
|---|
| 73 | _heap->set_gc_cause(GCCause::_no_gc); | 
|---|
| 74 | } | 
|---|
| 75 |  | 
|---|
| 76 | ShenandoahGCPauseMark::ShenandoahGCPauseMark(uint gc_id, SvcGCMarker::reason_type type) : | 
|---|
| 77 | _heap(ShenandoahHeap::heap()), _gc_id_mark(gc_id), _svc_gc_mark(type), _is_gc_active_mark() { | 
|---|
| 78 |  | 
|---|
| 79 | // FIXME: It seems that JMC throws away level 0 events, which are the Shenandoah | 
|---|
| 80 | // pause events. Create this pseudo level 0 event to push real events to level 1. | 
|---|
| 81 | _heap->gc_timer()->register_gc_phase_start( "Shenandoah", Ticks::now()); | 
|---|
| 82 | _trace_pause.initialize(_heap->stw_memory_manager(), _heap->gc_cause(), | 
|---|
| 83 | /* allMemoryPoolsAffected */    true, | 
|---|
| 84 | /* recordGCBeginTime = */       true, | 
|---|
| 85 | /* recordPreGCUsage = */        false, | 
|---|
| 86 | /* recordPeakUsage = */         false, | 
|---|
| 87 | /* recordPostGCUsage = */       false, | 
|---|
| 88 | /* recordAccumulatedGCTime = */ true, | 
|---|
| 89 | /* recordGCEndTime = */         true, | 
|---|
| 90 | /* countCollection = */         true | 
|---|
| 91 | ); | 
|---|
| 92 |  | 
|---|
| 93 | _heap->heuristics()->record_gc_start(); | 
|---|
| 94 | } | 
|---|
| 95 |  | 
|---|
| 96 | ShenandoahGCPauseMark::~ShenandoahGCPauseMark() { | 
|---|
| 97 | _heap->gc_timer()->register_gc_phase_end(Ticks::now()); | 
|---|
| 98 | _heap->heuristics()->record_gc_end(); | 
|---|
| 99 | } | 
|---|
| 100 |  | 
|---|
| 101 | ShenandoahGCPhase::ShenandoahGCPhase(const ShenandoahPhaseTimings::Phase phase) : | 
|---|
| 102 | _heap(ShenandoahHeap::heap()), _phase(phase) { | 
|---|
| 103 | assert(!Thread::current()->is_Worker_thread() && | 
|---|
| 104 | (Thread::current()->is_VM_thread() || | 
|---|
| 105 | Thread::current()->is_ConcurrentGC_thread()), | 
|---|
| 106 | "Must be set by these threads"); | 
|---|
| 107 | _parent_phase = _current_phase; | 
|---|
| 108 | _current_phase = phase; | 
|---|
| 109 |  | 
|---|
| 110 | _heap->phase_timings()->record_phase_start(_phase); | 
|---|
| 111 | } | 
|---|
| 112 |  | 
|---|
| 113 | ShenandoahGCPhase::~ShenandoahGCPhase() { | 
|---|
| 114 | _heap->phase_timings()->record_phase_end(_phase); | 
|---|
| 115 | _current_phase = _parent_phase; | 
|---|
| 116 | } | 
|---|
| 117 |  | 
|---|
| 118 | bool ShenandoahGCPhase::is_valid_phase(ShenandoahPhaseTimings::Phase phase) { | 
|---|
| 119 | return phase >= 0 && phase < ShenandoahPhaseTimings::_num_phases; | 
|---|
| 120 | } | 
|---|
| 121 |  | 
|---|
| 122 | bool ShenandoahGCPhase::is_root_work_phase() { | 
|---|
| 123 | switch(current_phase()) { | 
|---|
| 124 | case ShenandoahPhaseTimings::scan_roots: | 
|---|
| 125 | case ShenandoahPhaseTimings::update_roots: | 
|---|
| 126 | case ShenandoahPhaseTimings::init_evac: | 
|---|
| 127 | case ShenandoahPhaseTimings::final_update_refs_roots: | 
|---|
| 128 | case ShenandoahPhaseTimings::degen_gc_update_roots: | 
|---|
| 129 | case ShenandoahPhaseTimings::init_traversal_gc_work: | 
|---|
| 130 | case ShenandoahPhaseTimings::final_traversal_gc_work: | 
|---|
| 131 | case ShenandoahPhaseTimings::final_traversal_update_roots: | 
|---|
| 132 | case ShenandoahPhaseTimings::full_gc_roots: | 
|---|
| 133 | return true; | 
|---|
| 134 | default: | 
|---|
| 135 | return false; | 
|---|
| 136 | } | 
|---|
| 137 | } | 
|---|
| 138 |  | 
|---|
| 139 | ShenandoahAllocTrace::ShenandoahAllocTrace(size_t words_size, ShenandoahAllocRequest::Type alloc_type) { | 
|---|
| 140 | if (ShenandoahAllocationTrace) { | 
|---|
| 141 | _start = os::elapsedTime(); | 
|---|
| 142 | _size = words_size; | 
|---|
| 143 | _alloc_type = alloc_type; | 
|---|
| 144 | } else { | 
|---|
| 145 | _start = 0; | 
|---|
| 146 | _size = 0; | 
|---|
| 147 | _alloc_type = ShenandoahAllocRequest::Type(0); | 
|---|
| 148 | } | 
|---|
| 149 | } | 
|---|
| 150 |  | 
|---|
| 151 | ShenandoahAllocTrace::~ShenandoahAllocTrace() { | 
|---|
| 152 | if (ShenandoahAllocationTrace) { | 
|---|
| 153 | double stop = os::elapsedTime(); | 
|---|
| 154 | double duration_sec = stop - _start; | 
|---|
| 155 | double duration_us = duration_sec * 1000000; | 
|---|
| 156 | ShenandoahAllocTracker* tracker = ShenandoahHeap::heap()->alloc_tracker(); | 
|---|
| 157 | assert(tracker != NULL, "Must be"); | 
|---|
| 158 | tracker->record_alloc_latency(_size, _alloc_type, duration_us); | 
|---|
| 159 | if (duration_us > ShenandoahAllocationStallThreshold) { | 
|---|
| 160 | log_warning(gc)( "Allocation stall: %.0f us (threshold: "INTX_FORMAT " us)", | 
|---|
| 161 | duration_us, ShenandoahAllocationStallThreshold); | 
|---|
| 162 | } | 
|---|
| 163 | } | 
|---|
| 164 | } | 
|---|
| 165 |  | 
|---|
| 166 | ShenandoahWorkerSession::ShenandoahWorkerSession(uint worker_id) : _worker_id(worker_id) { | 
|---|
| 167 | Thread* thr = Thread::current(); | 
|---|
| 168 | assert(ShenandoahThreadLocalData::worker_id(thr) == ShenandoahThreadLocalData::INVALID_WORKER_ID, "Already set"); | 
|---|
| 169 | ShenandoahThreadLocalData::set_worker_id(thr, worker_id); | 
|---|
| 170 | } | 
|---|
| 171 |  | 
|---|
| 172 | ShenandoahConcurrentWorkerSession::~ShenandoahConcurrentWorkerSession() { | 
|---|
| 173 | _event.commit(GCId::current(), ShenandoahPhaseTimings::phase_name(ShenandoahGCPhase::current_phase())); | 
|---|
| 174 | } | 
|---|
| 175 |  | 
|---|
| 176 | ShenandoahParallelWorkerSession::~ShenandoahParallelWorkerSession() { | 
|---|
| 177 | _event.commit(GCId::current(), _worker_id, ShenandoahPhaseTimings::phase_name(ShenandoahGCPhase::current_phase())); | 
|---|
| 178 | } | 
|---|
| 179 | ShenandoahWorkerSession::~ShenandoahWorkerSession() { | 
|---|
| 180 | #ifdef ASSERT | 
|---|
| 181 | Thread* thr = Thread::current(); | 
|---|
| 182 | assert(ShenandoahThreadLocalData::worker_id(thr) != ShenandoahThreadLocalData::INVALID_WORKER_ID, "Must be set"); | 
|---|
| 183 | ShenandoahThreadLocalData::set_worker_id(thr, ShenandoahThreadLocalData::INVALID_WORKER_ID); | 
|---|
| 184 | #endif | 
|---|
| 185 | } | 
|---|
| 186 |  | 
|---|
| 187 | struct PhaseMap { | 
|---|
| 188 | WeakProcessorPhases::Phase            _weak_processor_phase; | 
|---|
| 189 | ShenandoahPhaseTimings::GCParPhases   _shenandoah_phase; | 
|---|
| 190 | }; | 
|---|
| 191 |  | 
|---|
| 192 | static const struct PhaseMap phase_mapping[] = { | 
|---|
| 193 | #if INCLUDE_JVMTI | 
|---|
| 194 | {WeakProcessorPhases::jvmti,                 ShenandoahPhaseTimings::JVMTIWeakRoots}, | 
|---|
| 195 | #endif | 
|---|
| 196 | #if INCLUDE_JFR | 
|---|
| 197 | {WeakProcessorPhases::jfr,                   ShenandoahPhaseTimings::JFRWeakRoots}, | 
|---|
| 198 | #endif | 
|---|
| 199 | {WeakProcessorPhases::jni,                   ShenandoahPhaseTimings::JNIWeakRoots}, | 
|---|
| 200 | {WeakProcessorPhases::stringtable,           ShenandoahPhaseTimings::StringTableRoots}, | 
|---|
| 201 | {WeakProcessorPhases::resolved_method_table, ShenandoahPhaseTimings::ResolvedMethodTableRoots}, | 
|---|
| 202 | {WeakProcessorPhases::vm,                    ShenandoahPhaseTimings::VMWeakRoots} | 
|---|
| 203 | }; | 
|---|
| 204 |  | 
|---|
| 205 | STATIC_ASSERT(sizeof(phase_mapping) / sizeof(PhaseMap) == WeakProcessorPhases::phase_count); | 
|---|
| 206 |  | 
|---|
| 207 | void ShenandoahTimingConverter::weak_processing_timing_to_shenandoah_timing(WeakProcessorPhaseTimes* weak_processing_timings, | 
|---|
| 208 | ShenandoahWorkerTimings* sh_worker_times) { | 
|---|
| 209 | assert(weak_processing_timings->max_threads() == weak_processing_timings->max_threads(), "Must match"); | 
|---|
| 210 | for (uint index = 0; index < WeakProcessorPhases::phase_count; index ++) { | 
|---|
| 211 | weak_processing_phase_to_shenandoah_phase(phase_mapping[index]._weak_processor_phase, | 
|---|
| 212 | weak_processing_timings, | 
|---|
| 213 | phase_mapping[index]._shenandoah_phase, | 
|---|
| 214 | sh_worker_times); | 
|---|
| 215 | } | 
|---|
| 216 | } | 
|---|
| 217 |  | 
|---|
| 218 | void ShenandoahTimingConverter::weak_processing_phase_to_shenandoah_phase(WeakProcessorPhases::Phase wpp, | 
|---|
| 219 | WeakProcessorPhaseTimes* weak_processing_timings, | 
|---|
| 220 | ShenandoahPhaseTimings::GCParPhases spp, | 
|---|
| 221 | ShenandoahWorkerTimings* sh_worker_times) { | 
|---|
| 222 | if (WeakProcessorPhases::is_serial(wpp)) { | 
|---|
| 223 | sh_worker_times->record_time_secs(spp, 0, weak_processing_timings->phase_time_sec(wpp)); | 
|---|
| 224 | } else { | 
|---|
| 225 | for (uint index = 0; index < weak_processing_timings->max_threads(); index ++) { | 
|---|
| 226 | sh_worker_times->record_time_secs(spp, index, weak_processing_timings->worker_time_sec(index, wpp)); | 
|---|
| 227 | } | 
|---|
| 228 | } | 
|---|
| 229 | } | 
|---|
| 230 |  | 
|---|