author | ehelin |
Fri, 28 Oct 2016 15:50:09 +0200 | |
changeset 42545 | af9f4bf4d6d8 |
parent 42048 | c1f066b53dd7 |
child 42557 | ee4c7799659d |
permissions | -rw-r--r-- |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
1 |
/* |
36367
313c87c050f7
8076463: Add logging for the preserve CM referents task
tschatzl
parents:
35907
diff
changeset
|
2 |
* Copyright (c) 2013, 2016, Oracle and/or its affiliates. All rights reserved. |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
3 |
* DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
4 |
* |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
5 |
* This code is free software; you can redistribute it and/or modify it |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
6 |
* under the terms of the GNU General Public License version 2 only, as |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
7 |
* published by the Free Software Foundation. |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
8 |
* |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
9 |
* This code is distributed in the hope that it will be useful, but WITHOUT |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
10 |
* ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
11 |
* FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
12 |
* version 2 for more details (a copy is included in the LICENSE file that |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
13 |
* accompanied this code). |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
14 |
* |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
15 |
* You should have received a copy of the GNU General Public License version |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
16 |
* 2 along with this work; if not, write to the Free Software Foundation, |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
17 |
* Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
18 |
* |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
19 |
* Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
20 |
* or visit www.oracle.com if you need additional information or have any |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
21 |
* questions. |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
22 |
* |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
23 |
*/ |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
24 |
|
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
25 |
#include "precompiled.hpp" |
30764 | 26 |
#include "gc/g1/g1CollectedHeap.inline.hpp" |
27 |
#include "gc/g1/g1GCPhaseTimes.hpp" |
|
38172
90f405aac699
8155524: HotCardCache shouldn't be part of ConcurrentG1Refine
kbarrett
parents:
37242
diff
changeset
|
28 |
#include "gc/g1/g1HotCardCache.hpp" |
30764 | 29 |
#include "gc/g1/g1StringDedup.hpp" |
33623 | 30 |
#include "gc/g1/workerDataArray.inline.hpp" |
36374 | 31 |
#include "memory/resourceArea.hpp" |
35061 | 32 |
#include "logging/log.hpp" |
35907
57bdb6243428
8148734: G1: Make G1GCPhaseTimes keep track of the start GC time
brutisso
parents:
35887
diff
changeset
|
33 |
#include "runtime/timer.hpp" |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
34 |
#include "runtime/os.hpp" |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
35 |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
36 |
static const char* Indents[5] = {"", " ", " ", " ", " "}; |
35061 | 37 |
|
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
38 |
G1GCPhaseTimes::G1GCPhaseTimes(uint max_gc_threads) : |
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
39 |
_max_gc_threads(max_gc_threads), |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
40 |
_gc_start_counter(0), |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
41 |
_gc_pause_time_ms(0.0) |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
42 |
{ |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
43 |
assert(max_gc_threads > 0, "Must have some GC threads"); |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
44 |
|
36374 | 45 |
_gc_par_phases[GCWorkerStart] = new WorkerDataArray<double>(max_gc_threads, "GC Worker Start (ms):"); |
46 |
_gc_par_phases[ExtRootScan] = new WorkerDataArray<double>(max_gc_threads, "Ext Root Scanning (ms):"); |
|
29694
442c3305ba39
8027962: Per-phase timing measurements for strong roots processing
brutisso
parents:
29680
diff
changeset
|
47 |
|
442c3305ba39
8027962: Per-phase timing measurements for strong roots processing
brutisso
parents:
29680
diff
changeset
|
48 |
// Root scanning phases |
36374 | 49 |
_gc_par_phases[ThreadRoots] = new WorkerDataArray<double>(max_gc_threads, "Thread Roots (ms):"); |
50 |
_gc_par_phases[StringTableRoots] = new WorkerDataArray<double>(max_gc_threads, "StringTable Roots (ms):"); |
|
51 |
_gc_par_phases[UniverseRoots] = new WorkerDataArray<double>(max_gc_threads, "Universe Roots (ms):"); |
|
52 |
_gc_par_phases[JNIRoots] = new WorkerDataArray<double>(max_gc_threads, "JNI Handles Roots (ms):"); |
|
53 |
_gc_par_phases[ObjectSynchronizerRoots] = new WorkerDataArray<double>(max_gc_threads, "ObjectSynchronizer Roots (ms):"); |
|
54 |
_gc_par_phases[FlatProfilerRoots] = new WorkerDataArray<double>(max_gc_threads, "FlatProfiler Roots (ms):"); |
|
55 |
_gc_par_phases[ManagementRoots] = new WorkerDataArray<double>(max_gc_threads, "Management Roots (ms):"); |
|
56 |
_gc_par_phases[SystemDictionaryRoots] = new WorkerDataArray<double>(max_gc_threads, "SystemDictionary Roots (ms):"); |
|
57 |
_gc_par_phases[CLDGRoots] = new WorkerDataArray<double>(max_gc_threads, "CLDG Roots (ms):"); |
|
58 |
_gc_par_phases[JVMTIRoots] = new WorkerDataArray<double>(max_gc_threads, "JVMTI Roots (ms):"); |
|
59 |
_gc_par_phases[CMRefRoots] = new WorkerDataArray<double>(max_gc_threads, "CM RefProcessor Roots (ms):"); |
|
60 |
_gc_par_phases[WaitForStrongCLD] = new WorkerDataArray<double>(max_gc_threads, "Wait For Strong CLD (ms):"); |
|
61 |
_gc_par_phases[WeakCLDRoots] = new WorkerDataArray<double>(max_gc_threads, "Weak CLD Roots (ms):"); |
|
62 |
_gc_par_phases[SATBFiltering] = new WorkerDataArray<double>(max_gc_threads, "SATB Filtering (ms):"); |
|
29694
442c3305ba39
8027962: Per-phase timing measurements for strong roots processing
brutisso
parents:
29680
diff
changeset
|
63 |
|
36374 | 64 |
_gc_par_phases[UpdateRS] = new WorkerDataArray<double>(max_gc_threads, "Update RS (ms):"); |
38172
90f405aac699
8155524: HotCardCache shouldn't be part of ConcurrentG1Refine
kbarrett
parents:
37242
diff
changeset
|
65 |
if (G1HotCardCache::default_use_cache()) { |
36374 | 66 |
_gc_par_phases[ScanHCC] = new WorkerDataArray<double>(max_gc_threads, "Scan HCC (ms):"); |
67 |
} else { |
|
68 |
_gc_par_phases[ScanHCC] = NULL; |
|
69 |
} |
|
70 |
_gc_par_phases[ScanRS] = new WorkerDataArray<double>(max_gc_threads, "Scan RS (ms):"); |
|
71 |
_gc_par_phases[CodeRoots] = new WorkerDataArray<double>(max_gc_threads, "Code Root Scanning (ms):"); |
|
72 |
_gc_par_phases[ObjCopy] = new WorkerDataArray<double>(max_gc_threads, "Object Copy (ms):"); |
|
73 |
_gc_par_phases[Termination] = new WorkerDataArray<double>(max_gc_threads, "Termination (ms):"); |
|
74 |
_gc_par_phases[GCWorkerTotal] = new WorkerDataArray<double>(max_gc_threads, "GC Worker Total (ms):"); |
|
75 |
_gc_par_phases[GCWorkerEnd] = new WorkerDataArray<double>(max_gc_threads, "GC Worker End (ms):"); |
|
76 |
_gc_par_phases[Other] = new WorkerDataArray<double>(max_gc_threads, "GC Worker Other (ms):"); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
77 |
|
36374 | 78 |
_update_rs_processed_buffers = new WorkerDataArray<size_t>(max_gc_threads, "Processed Buffers:"); |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
79 |
_gc_par_phases[UpdateRS]->link_thread_work_items(_update_rs_processed_buffers); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
80 |
|
36374 | 81 |
_termination_attempts = new WorkerDataArray<size_t>(max_gc_threads, "Termination Attempts:"); |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
82 |
_gc_par_phases[Termination]->link_thread_work_items(_termination_attempts); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
83 |
|
36374 | 84 |
if (UseStringDeduplication) { |
85 |
_gc_par_phases[StringDedupQueueFixup] = new WorkerDataArray<double>(max_gc_threads, "Queue Fixup (ms):"); |
|
86 |
_gc_par_phases[StringDedupTableFixup] = new WorkerDataArray<double>(max_gc_threads, "Table Fixup (ms):"); |
|
87 |
} else { |
|
88 |
_gc_par_phases[StringDedupQueueFixup] = NULL; |
|
89 |
_gc_par_phases[StringDedupTableFixup] = NULL; |
|
90 |
} |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
91 |
|
36374 | 92 |
_gc_par_phases[RedirtyCards] = new WorkerDataArray<double>(max_gc_threads, "Parallel Redirty (ms):"); |
93 |
_redirtied_cards = new WorkerDataArray<size_t>(max_gc_threads, "Redirtied Cards:"); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
94 |
_gc_par_phases[RedirtyCards]->link_thread_work_items(_redirtied_cards); |
36367
313c87c050f7
8076463: Add logging for the preserve CM referents task
tschatzl
parents:
35907
diff
changeset
|
95 |
|
39979 | 96 |
_gc_par_phases[YoungFreeCSet] = new WorkerDataArray<double>(max_gc_threads, "Young Free Collection Set (ms):"); |
97 |
_gc_par_phases[NonYoungFreeCSet] = new WorkerDataArray<double>(max_gc_threads, "Non-Young Free Collection Set (ms):"); |
|
98 |
||
36374 | 99 |
_gc_par_phases[PreserveCMReferents] = new WorkerDataArray<double>(max_gc_threads, "Parallel Preserve CM Refs (ms):"); |
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
100 |
|
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
101 |
reset(); |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
102 |
} |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
103 |
|
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
104 |
void G1GCPhaseTimes::reset() { |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
105 |
_cur_collection_par_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
106 |
_cur_collection_code_root_fixup_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
107 |
_cur_strong_code_root_purge_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
108 |
_cur_evac_fail_recalc_used = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
109 |
_cur_evac_fail_restore_remsets = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
110 |
_cur_evac_fail_remove_self_forwards = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
111 |
_cur_string_dedup_fixup_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
112 |
_cur_clear_ct_time_ms = 0.0; |
33577 | 113 |
_cur_expand_heap_time_ms = 0.0; |
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
114 |
_cur_ref_proc_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
115 |
_cur_ref_enq_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
116 |
_cur_collection_start_sec = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
117 |
_root_region_scan_wait_time_ms = 0.0; |
34612
ed475e8089ef
8144145: G1GCPhaseTimes should allow externally accounted time
ehelin
parents:
33739
diff
changeset
|
118 |
_external_accounted_time_ms = 0.0; |
42048
c1f066b53dd7
8160055: Misplaced call to ClassLoaderDataGraph::clear_claimed_marks during initial mark
jprovino
parents:
39979
diff
changeset
|
119 |
_recorded_clear_claimed_marks_time_ms = 0.0; |
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
120 |
_recorded_young_cset_choice_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
121 |
_recorded_non_young_cset_choice_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
122 |
_recorded_redirty_logged_cards_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
123 |
_recorded_preserve_cm_referents_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
124 |
_recorded_merge_pss_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
125 |
_recorded_total_free_cset_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
126 |
_recorded_serial_free_cset_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
127 |
_cur_fast_reclaim_humongous_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
128 |
_cur_fast_reclaim_humongous_register_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
129 |
_cur_fast_reclaim_humongous_total = 0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
130 |
_cur_fast_reclaim_humongous_candidates = 0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
131 |
_cur_fast_reclaim_humongous_reclaimed = 0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
132 |
_cur_verify_before_time_ms = 0.0; |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
133 |
_cur_verify_after_time_ms = 0.0; |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
134 |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
135 |
for (int i = 0; i < GCParPhasesSentinel; i++) { |
36374 | 136 |
if (_gc_par_phases[i] != NULL) { |
137 |
_gc_par_phases[i]->reset(); |
|
138 |
} |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
139 |
} |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
140 |
} |
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
141 |
|
42545
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
142 |
void G1GCPhaseTimes::note_gc_start() { |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
143 |
_gc_start_counter = os::elapsed_counter(); |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
144 |
reset(); |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
145 |
} |
af9f4bf4d6d8
8168904: Initialize and reset G1 phase times to zero
ehelin
parents:
42048
diff
changeset
|
146 |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
147 |
#define ASSERT_PHASE_UNINITIALIZED(phase) \ |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
148 |
assert(_gc_par_phases[phase]->get(i) == uninitialized, "Phase " #phase " reported for thread that was not started"); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
149 |
|
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
150 |
double G1GCPhaseTimes::worker_time(GCParPhases phase, uint worker) { |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
151 |
double value = _gc_par_phases[phase]->get(worker); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
152 |
if (value != WorkerDataArray<double>::uninitialized()) { |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
153 |
return value; |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
154 |
} |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
155 |
return 0.0; |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
156 |
} |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
157 |
|
13516
f7adc27fb367
7178363: G1: Remove the serial code for PrintGCDetails and make it a special case of the parallel code
brutisso
parents:
13288
diff
changeset
|
158 |
void G1GCPhaseTimes::note_gc_end() { |
35907
57bdb6243428
8148734: G1: Make G1GCPhaseTimes keep track of the start GC time
brutisso
parents:
35887
diff
changeset
|
159 |
_gc_pause_time_ms = TimeHelper::counter_to_millis(os::elapsed_counter() - _gc_start_counter); |
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
160 |
|
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
161 |
double uninitialized = WorkerDataArray<double>::uninitialized(); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
162 |
|
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
163 |
for (uint i = 0; i < _max_gc_threads; i++) { |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
164 |
double worker_start = _gc_par_phases[GCWorkerStart]->get(i); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
165 |
if (worker_start != uninitialized) { |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
166 |
assert(_gc_par_phases[GCWorkerEnd]->get(i) != uninitialized, "Worker started but not ended."); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
167 |
double total_worker_time = _gc_par_phases[GCWorkerEnd]->get(i) - _gc_par_phases[GCWorkerStart]->get(i); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
168 |
record_time_secs(GCWorkerTotal, i , total_worker_time); |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
169 |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
170 |
double worker_known_time = |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
171 |
worker_time(ExtRootScan, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
172 |
+ worker_time(SATBFiltering, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
173 |
+ worker_time(UpdateRS, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
174 |
+ worker_time(ScanRS, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
175 |
+ worker_time(CodeRoots, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
176 |
+ worker_time(ObjCopy, i) |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
177 |
+ worker_time(Termination, i); |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
178 |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
179 |
record_time_secs(Other, i, total_worker_time - worker_known_time); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
180 |
} else { |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
181 |
// Make sure all slots are uninitialized since this thread did not seem to have been started |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
182 |
ASSERT_PHASE_UNINITIALIZED(GCWorkerEnd); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
183 |
ASSERT_PHASE_UNINITIALIZED(ExtRootScan); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
184 |
ASSERT_PHASE_UNINITIALIZED(SATBFiltering); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
185 |
ASSERT_PHASE_UNINITIALIZED(UpdateRS); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
186 |
ASSERT_PHASE_UNINITIALIZED(ScanRS); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
187 |
ASSERT_PHASE_UNINITIALIZED(CodeRoots); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
188 |
ASSERT_PHASE_UNINITIALIZED(ObjCopy); |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
189 |
ASSERT_PHASE_UNINITIALIZED(Termination); |
36374 | 190 |
} |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
191 |
} |
23472 | 192 |
} |
193 |
||
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
194 |
#undef ASSERT_PHASE_UNINITIALIZED |
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
195 |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
196 |
// record the time a phase took in seconds |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
197 |
void G1GCPhaseTimes::record_time_secs(GCParPhases phase, uint worker_i, double secs) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
198 |
_gc_par_phases[phase]->set(worker_i, secs); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
199 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
200 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
201 |
// add a number of seconds to a phase |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
202 |
void G1GCPhaseTimes::add_time_secs(GCParPhases phase, uint worker_i, double secs) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
203 |
_gc_par_phases[phase]->add(worker_i, secs); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
204 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
205 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
206 |
void G1GCPhaseTimes::record_thread_work_item(GCParPhases phase, uint worker_i, size_t count) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
207 |
_gc_par_phases[phase]->set_thread_work_item(worker_i, count); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
208 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
209 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
210 |
// return the average time for a phase in milliseconds |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
211 |
double G1GCPhaseTimes::average_time_ms(GCParPhases phase) { |
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
212 |
return _gc_par_phases[phase]->average() * 1000.0; |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
213 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
214 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
215 |
size_t G1GCPhaseTimes::sum_thread_work_items(GCParPhases phase) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
216 |
assert(_gc_par_phases[phase]->thread_work_items() != NULL, "No sub count"); |
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
217 |
return _gc_par_phases[phase]->thread_work_items()->sum(); |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
218 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
219 |
|
36374 | 220 |
template <class T> |
221 |
void G1GCPhaseTimes::details(T* phase, const char* indent) { |
|
37242 | 222 |
Log(gc, phases, task) log; |
36374 | 223 |
if (log.is_level(LogLevel::Trace)) { |
224 |
outputStream* trace_out = log.trace_stream(); |
|
225 |
trace_out->print("%s", indent); |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
226 |
phase->print_details_on(trace_out); |
36374 | 227 |
} |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
228 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
229 |
|
36374 | 230 |
void G1GCPhaseTimes::log_phase(WorkerDataArray<double>* phase, uint indent, outputStream* out, bool print_sum) { |
231 |
out->print("%s", Indents[indent]); |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
232 |
phase->print_summary_on(out, print_sum); |
36374 | 233 |
details(phase, Indents[indent]); |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
234 |
|
36374 | 235 |
WorkerDataArray<size_t>* work_items = phase->thread_work_items(); |
236 |
if (work_items != NULL) { |
|
237 |
out->print("%s", Indents[indent + 1]); |
|
37214
bc4e0e0995e6
8152952: Allow G1 phase logging to use individual number of threads
brutisso
parents:
37209
diff
changeset
|
238 |
work_items->print_summary_on(out, true); |
36374 | 239 |
details(work_items, Indents[indent + 1]); |
240 |
} |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
241 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
242 |
|
36374 | 243 |
void G1GCPhaseTimes::debug_phase(WorkerDataArray<double>* phase) { |
37242 | 244 |
Log(gc, phases) log; |
36374 | 245 |
if (log.is_level(LogLevel::Debug)) { |
246 |
ResourceMark rm; |
|
247 |
log_phase(phase, 2, log.debug_stream(), true); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
248 |
} |
36374 | 249 |
} |
35061 | 250 |
|
36374 | 251 |
void G1GCPhaseTimes::trace_phase(WorkerDataArray<double>* phase, bool print_sum) { |
37242 | 252 |
Log(gc, phases) log; |
36374 | 253 |
if (log.is_level(LogLevel::Trace)) { |
254 |
ResourceMark rm; |
|
255 |
log_phase(phase, 3, log.trace_stream(), print_sum); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
256 |
} |
36374 | 257 |
} |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
258 |
|
36374 | 259 |
#define PHASE_DOUBLE_FORMAT "%s%s: %.1lfms" |
260 |
#define PHASE_SIZE_FORMAT "%s%s: " SIZE_FORMAT |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
261 |
|
36374 | 262 |
#define info_line(str, value) \ |
263 |
log_info(gc, phases)(PHASE_DOUBLE_FORMAT, Indents[1], str, value); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
264 |
|
36374 | 265 |
#define debug_line(str, value) \ |
266 |
log_debug(gc, phases)(PHASE_DOUBLE_FORMAT, Indents[2], str, value); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
267 |
|
36374 | 268 |
#define trace_line(str, value) \ |
269 |
log_trace(gc, phases)(PHASE_DOUBLE_FORMAT, Indents[3], str, value); |
|
35061 | 270 |
|
36374 | 271 |
#define trace_line_sz(str, value) \ |
272 |
log_trace(gc, phases)(PHASE_SIZE_FORMAT, Indents[3], str, value); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
273 |
|
36374 | 274 |
#define trace_line_ms(str, value) \ |
275 |
log_trace(gc, phases)(PHASE_SIZE_FORMAT, Indents[3], str, value); |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
276 |
|
36374 | 277 |
#define info_line_and_account(str, value) \ |
278 |
info_line(str, value); \ |
|
279 |
accounted_time_ms += value; |
|
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
280 |
|
35907
57bdb6243428
8148734: G1: Make G1GCPhaseTimes keep track of the start GC time
brutisso
parents:
35887
diff
changeset
|
281 |
void G1GCPhaseTimes::print() { |
33738
1708291bd3d7
8140508: Add utility method for logging phases to G1CollectorPolicy
ehelin
parents:
33623
diff
changeset
|
282 |
note_gc_end(); |
1708291bd3d7
8140508: Add utility method for logging phases to G1CollectorPolicy
ehelin
parents:
33623
diff
changeset
|
283 |
|
36374 | 284 |
double accounted_time_ms = _external_accounted_time_ms; |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
285 |
if (_root_region_scan_wait_time_ms > 0.0) { |
36374 | 286 |
info_line_and_account("Root Region Scan Waiting", _root_region_scan_wait_time_ms); |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
287 |
} |
27251
7d667f91ec8d
6979279: remove special-case code for ParallelGCThreads==0
mlarsson
parents:
26701
diff
changeset
|
288 |
|
36374 | 289 |
info_line_and_account("Evacuate Collection Set", _cur_collection_par_time_ms); |
290 |
trace_phase(_gc_par_phases[GCWorkerStart], false); |
|
291 |
debug_phase(_gc_par_phases[ExtRootScan]); |
|
292 |
for (int i = ThreadRoots; i <= SATBFiltering; i++) { |
|
293 |
trace_phase(_gc_par_phases[i]); |
|
294 |
} |
|
295 |
debug_phase(_gc_par_phases[UpdateRS]); |
|
38172
90f405aac699
8155524: HotCardCache shouldn't be part of ConcurrentG1Refine
kbarrett
parents:
37242
diff
changeset
|
296 |
if (G1HotCardCache::default_use_cache()) { |
36374 | 297 |
trace_phase(_gc_par_phases[ScanHCC]); |
298 |
} |
|
299 |
debug_phase(_gc_par_phases[ScanRS]); |
|
300 |
debug_phase(_gc_par_phases[CodeRoots]); |
|
301 |
debug_phase(_gc_par_phases[ObjCopy]); |
|
302 |
debug_phase(_gc_par_phases[Termination]); |
|
303 |
debug_phase(_gc_par_phases[Other]); |
|
304 |
debug_phase(_gc_par_phases[GCWorkerTotal]); |
|
305 |
trace_phase(_gc_par_phases[GCWorkerEnd], false); |
|
306 |
||
307 |
info_line_and_account("Code Roots", _cur_collection_code_root_fixup_time_ms + _cur_strong_code_root_purge_time_ms); |
|
308 |
debug_line("Code Roots Fixup", _cur_collection_code_root_fixup_time_ms); |
|
309 |
debug_line("Code Roots Purge", _cur_strong_code_root_purge_time_ms); |
|
310 |
||
23472 | 311 |
if (G1StringDedup::is_enabled()) { |
36374 | 312 |
info_line_and_account("String Dedup Fixup", _cur_string_dedup_fixup_time_ms); |
313 |
debug_phase(_gc_par_phases[StringDedupQueueFixup]); |
|
314 |
debug_phase(_gc_par_phases[StringDedupTableFixup]); |
|
23472 | 315 |
} |
36374 | 316 |
info_line_and_account("Clear Card Table", _cur_clear_ct_time_ms); |
317 |
info_line_and_account("Expand Heap After Collection", _cur_expand_heap_time_ms); |
|
318 |
||
39979 | 319 |
info_line_and_account("Free Collection Set", _recorded_total_free_cset_time_ms); |
320 |
debug_line("Free Collection Set Serial", _recorded_serial_free_cset_time_ms); |
|
321 |
debug_phase(_gc_par_phases[YoungFreeCSet]); |
|
322 |
debug_phase(_gc_par_phases[NonYoungFreeCSet]); |
|
323 |
||
36374 | 324 |
info_line_and_account("Merge Per-Thread State", _recorded_merge_pss_time_ms); |
325 |
||
326 |
info_line("Other", _gc_pause_time_ms - accounted_time_ms); |
|
13516
f7adc27fb367
7178363: G1: Remove the serial code for PrintGCDetails and make it a special case of the parallel code
brutisso
parents:
13288
diff
changeset
|
327 |
if (_cur_verify_before_time_ms > 0.0) { |
36374 | 328 |
debug_line("Verify Before", _cur_verify_before_time_ms); |
13516
f7adc27fb367
7178363: G1: Remove the serial code for PrintGCDetails and make it a special case of the parallel code
brutisso
parents:
13288
diff
changeset
|
329 |
} |
23455
e541bff96524
8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents:
23454
diff
changeset
|
330 |
if (G1CollectedHeap::heap()->evacuation_failed()) { |
e541bff96524
8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents:
23454
diff
changeset
|
331 |
double evac_fail_handling = _cur_evac_fail_recalc_used + _cur_evac_fail_remove_self_forwards + |
e541bff96524
8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents:
23454
diff
changeset
|
332 |
_cur_evac_fail_restore_remsets; |
36374 | 333 |
debug_line("Evacuation Failure", evac_fail_handling); |
334 |
trace_line("Recalculate Used", _cur_evac_fail_recalc_used); |
|
335 |
trace_line("Remove Self Forwards",_cur_evac_fail_remove_self_forwards); |
|
336 |
trace_line("Restore RemSet", _cur_evac_fail_restore_remsets); |
|
23455
e541bff96524
8035654: Add times for evacuation failure handling in "Other" time
tschatzl
parents:
23454
diff
changeset
|
337 |
} |
36374 | 338 |
debug_line("Choose CSet", (_recorded_young_cset_choice_time_ms + _recorded_non_young_cset_choice_time_ms)); |
339 |
debug_line("Preserve CM Refs", _recorded_preserve_cm_referents_time_ms); |
|
37209
c2920cd096aa
8153065: "Preserve CM refs" log message disconnected from "Parallel Preserve CM refs"
tschatzl
parents:
36387
diff
changeset
|
340 |
trace_phase(_gc_par_phases[PreserveCMReferents]); |
36387
1c1c1c1aa3ad
8058944: Unify the reporting strings for the GC debug level logging in G1
brutisso
parents:
36374
diff
changeset
|
341 |
debug_line("Reference Processing", _cur_ref_proc_time_ms); |
1c1c1c1aa3ad
8058944: Unify the reporting strings for the GC debug level logging in G1
brutisso
parents:
36374
diff
changeset
|
342 |
debug_line("Reference Enqueuing", _cur_ref_enq_time_ms); |
36374 | 343 |
debug_line("Redirty Cards", _recorded_redirty_logged_cards_time_ms); |
42048
c1f066b53dd7
8160055: Misplaced call to ClassLoaderDataGraph::clear_claimed_marks during initial mark
jprovino
parents:
39979
diff
changeset
|
344 |
if (_recorded_clear_claimed_marks_time_ms > 0.0) { |
c1f066b53dd7
8160055: Misplaced call to ClassLoaderDataGraph::clear_claimed_marks during initial mark
jprovino
parents:
39979
diff
changeset
|
345 |
debug_line("Clear Claimed Marks", _recorded_clear_claimed_marks_time_ms); |
c1f066b53dd7
8160055: Misplaced call to ClassLoaderDataGraph::clear_claimed_marks during initial mark
jprovino
parents:
39979
diff
changeset
|
346 |
} |
c1f066b53dd7
8160055: Misplaced call to ClassLoaderDataGraph::clear_claimed_marks during initial mark
jprovino
parents:
39979
diff
changeset
|
347 |
|
36374 | 348 |
trace_phase(_gc_par_phases[RedirtyCards]); |
28379
e6784fc8fff2
8048179: Early reclaim of large objects that are referenced by a few objects
tschatzl
parents:
27251
diff
changeset
|
349 |
if (G1EagerReclaimHumongousObjects) { |
36374 | 350 |
debug_line("Humongous Register", _cur_fast_reclaim_humongous_register_time_ms); |
351 |
trace_line_sz("Humongous Total", _cur_fast_reclaim_humongous_total); |
|
352 |
trace_line_sz("Humongous Candidate", _cur_fast_reclaim_humongous_candidates); |
|
353 |
debug_line("Humongous Reclaim", _cur_fast_reclaim_humongous_time_ms); |
|
354 |
trace_line_sz("Humongous Reclaimed", _cur_fast_reclaim_humongous_reclaimed); |
|
25889
221296ac4359
8027959: Early reclamation of large objects in G1
tschatzl
parents:
25492
diff
changeset
|
355 |
} |
13516
f7adc27fb367
7178363: G1: Remove the serial code for PrintGCDetails and make it a special case of the parallel code
brutisso
parents:
13288
diff
changeset
|
356 |
if (_cur_verify_after_time_ms > 0.0) { |
36374 | 357 |
debug_line("Verify After", _cur_verify_after_time_ms); |
13516
f7adc27fb367
7178363: G1: Remove the serial code for PrintGCDetails and make it a special case of the parallel code
brutisso
parents:
13288
diff
changeset
|
358 |
} |
13288
331d5b6725f3
7178361: G1: Make sure that PrintGC and PrintGCDetails use the same timing for the GC pause
brutisso
parents:
diff
changeset
|
359 |
} |
29680
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
360 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
361 |
G1GCParPhaseTimesTracker::G1GCParPhaseTimesTracker(G1GCPhaseTimes* phase_times, G1GCPhaseTimes::GCParPhases phase, uint worker_id) : |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
362 |
_phase_times(phase_times), _phase(phase), _worker_id(worker_id) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
363 |
if (_phase_times != NULL) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
364 |
_start_time = os::elapsedTime(); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
365 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
366 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
367 |
|
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
368 |
G1GCParPhaseTimesTracker::~G1GCParPhaseTimesTracker() { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
369 |
if (_phase_times != NULL) { |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
370 |
_phase_times->record_time_secs(_phase, _worker_id, os::elapsedTime() - _start_time); |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
371 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
372 |
} |
e5203ed6d805
8074037: Refactor the G1GCPhaseTime logging to make it easier to add new phases
brutisso
parents:
28379
diff
changeset
|
373 |