author | tschatzl |
Mon, 18 Jun 2018 12:11:59 +0200 | |
changeset 50606 | 8f1d5d706bdd |
parent 50605 | 7f63c74f0974 |
child 51025 | e4ca45413ddd |
permissions | -rw-r--r-- |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
1 |
/* |
49360 | 2 |
* Copyright (c) 2017, 2018, Oracle and/or its affiliates. All rights reserved. |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
3 |
* DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
4 |
* |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
5 |
* This code is free software; you can redistribute it and/or modify it |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
6 |
* under the terms of the GNU General Public License version 2 only, as |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
7 |
* published by the Free Software Foundation. |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
8 |
* |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
9 |
* This code is distributed in the hope that it will be useful, but WITHOUT |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
10 |
* ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
11 |
* FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
12 |
* version 2 for more details (a copy is included in the LICENSE file that |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
13 |
* accompanied this code). |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
14 |
* |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
15 |
* You should have received a copy of the GNU General Public License version |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
16 |
* 2 along with this work; if not, write to the Free Software Foundation, |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
17 |
* Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
18 |
* |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
19 |
* Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
20 |
* or visit www.oracle.com if you need additional information or have any |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
21 |
* questions. |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
22 |
* |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
23 |
*/ |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
24 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
25 |
#include "precompiled.hpp" |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
26 |
#include "gc/shared/gcTimer.hpp" |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
27 |
#include "gc/shared/referenceProcessorPhaseTimes.hpp" |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
28 |
#include "gc/shared/referenceProcessor.inline.hpp" |
49360 | 29 |
#include "gc/shared/workerDataArray.inline.hpp" |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
30 |
#include "logging/log.hpp" |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
31 |
#include "logging/logStream.hpp" |
49360 | 32 |
#include "memory/allocation.inline.hpp" |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
33 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
34 |
#define ASSERT_REF_TYPE(ref_type) assert((ref_type) >= REF_SOFT && (ref_type) <= REF_PHANTOM, \ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
35 |
"Invariant (%d)", (int)ref_type) |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
36 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
37 |
#define ASSERT_PHASE(phase) assert((phase) >= ReferenceProcessor::RefPhase1 && \ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
38 |
(phase) < ReferenceProcessor::RefPhaseMax, \ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
39 |
"Invariant (%d)", (int)phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
40 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
41 |
#define ASSERT_SUB_PHASE(phase) assert((phase) >= ReferenceProcessor::SoftRefSubPhase1 && \ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
42 |
(phase) < ReferenceProcessor::RefSubPhaseMax, \ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
43 |
"Invariant (%d)", (int)phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
44 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
45 |
static const char* SubPhasesParWorkTitle[ReferenceProcessor::RefSubPhaseMax] = { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
46 |
"SoftRef (ms):", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
47 |
"SoftRef (ms):", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
48 |
"WeakRef (ms):", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
49 |
"FinalRef (ms):", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
50 |
"FinalRef (ms):", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
51 |
"PhantomRef (ms):" |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
52 |
}; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
53 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
54 |
static const char* Phase2ParWorkTitle = "Total (ms):"; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
55 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
56 |
static const char* SubPhasesSerWorkTitle[ReferenceProcessor::RefSubPhaseMax] = { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
57 |
"SoftRef:", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
58 |
"SoftRef:", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
59 |
"WeakRef:", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
60 |
"FinalRef:", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
61 |
"FinalRef:", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
62 |
"PhantomRef:" |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
63 |
}; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
64 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
65 |
static const char* Phase2SerWorkTitle = "Total:"; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
66 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
67 |
static const char* Indents[6] = {"", " ", " ", " ", " ", " "}; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
68 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
69 |
static const char* PhaseNames[ReferenceProcessor::RefPhaseMax] = { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
70 |
"Reconsider SoftReferences", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
71 |
"Notify Soft/WeakReferences", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
72 |
"Notify and keep alive finalizable", |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
73 |
"Notify PhantomReferences" |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
74 |
}; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
75 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
76 |
static const char* ReferenceTypeNames[REF_PHANTOM + 1] = { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
77 |
"None", "Other", "SoftReference", "WeakReference", "FinalReference", "PhantomReference" |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
78 |
}; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
79 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
80 |
STATIC_ASSERT((REF_PHANTOM + 1) == ARRAY_SIZE(ReferenceTypeNames)); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
81 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
82 |
static const char* phase_enum_2_phase_string(ReferenceProcessor::RefProcPhases phase) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
83 |
assert(phase >= ReferenceProcessor::RefPhase1 && phase <= ReferenceProcessor::RefPhaseMax, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
84 |
"Invalid reference processing phase (%d)", phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
85 |
return PhaseNames[phase]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
86 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
87 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
88 |
static const char* ref_type_2_string(ReferenceType ref_type) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
89 |
ASSERT_REF_TYPE(ref_type); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
90 |
return ReferenceTypeNames[ref_type]; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
91 |
} |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
92 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
93 |
RefProcWorkerTimeTracker::RefProcWorkerTimeTracker(WorkerDataArray<double>* worker_time, uint worker_id) : |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
94 |
_worker_time(worker_time), _start_time(os::elapsedTime()), _worker_id(worker_id) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
95 |
assert(worker_time != NULL, "Invariant"); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
96 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
97 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
98 |
RefProcWorkerTimeTracker::~RefProcWorkerTimeTracker() { |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
99 |
double result = os::elapsedTime() - _start_time; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
100 |
_worker_time->set(_worker_id, result); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
101 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
102 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
103 |
RefProcSubPhasesWorkerTimeTracker::RefProcSubPhasesWorkerTimeTracker(ReferenceProcessor::RefProcSubPhases phase, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
104 |
ReferenceProcessorPhaseTimes* phase_times, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
105 |
uint worker_id) : |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
106 |
RefProcWorkerTimeTracker(phase_times->sub_phase_worker_time_sec(phase), worker_id) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
107 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
108 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
109 |
RefProcSubPhasesWorkerTimeTracker::~RefProcSubPhasesWorkerTimeTracker() { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
110 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
111 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
112 |
RefProcPhaseTimeBaseTracker::RefProcPhaseTimeBaseTracker(const char* title, |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
113 |
ReferenceProcessor::RefProcPhases phase_number, |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
114 |
ReferenceProcessorPhaseTimes* phase_times) : |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
115 |
_phase_times(phase_times), _start_ticks(), _end_ticks(), _phase_number(phase_number) { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
116 |
assert(_phase_times != NULL, "Invariant"); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
117 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
118 |
_start_ticks.stamp(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
119 |
if (_phase_times->gc_timer() != NULL) { |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
120 |
_phase_times->gc_timer()->register_gc_phase_start(title, _start_ticks); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
121 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
122 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
123 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
124 |
Ticks RefProcPhaseTimeBaseTracker::end_ticks() { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
125 |
// If ASSERT is defined, the default value of Ticks will be -2. |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
126 |
if (_end_ticks.value() <= 0) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
127 |
_end_ticks.stamp(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
128 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
129 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
130 |
return _end_ticks; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
131 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
132 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
133 |
double RefProcPhaseTimeBaseTracker::elapsed_time() { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
134 |
jlong end_value = end_ticks().value(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
135 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
136 |
return TimeHelper::counter_to_millis(end_value - _start_ticks.value()); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
137 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
138 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
139 |
RefProcPhaseTimeBaseTracker::~RefProcPhaseTimeBaseTracker() { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
140 |
if (_phase_times->gc_timer() != NULL) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
141 |
Ticks ticks = end_ticks(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
142 |
_phase_times->gc_timer()->register_gc_phase_end(ticks); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
143 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
144 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
145 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
146 |
RefProcBalanceQueuesTimeTracker::RefProcBalanceQueuesTimeTracker(ReferenceProcessor::RefProcPhases phase_number, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
147 |
ReferenceProcessorPhaseTimes* phase_times) : |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
148 |
RefProcPhaseTimeBaseTracker("Balance queues", phase_number, phase_times) {} |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
149 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
150 |
RefProcBalanceQueuesTimeTracker::~RefProcBalanceQueuesTimeTracker() { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
151 |
double elapsed = elapsed_time(); |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
152 |
phase_times()->set_balance_queues_time_ms(_phase_number, elapsed); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
153 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
154 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
155 |
RefProcPhaseTimeTracker::RefProcPhaseTimeTracker(ReferenceProcessor::RefProcPhases phase_number, |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
156 |
ReferenceProcessorPhaseTimes* phase_times) : |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
157 |
RefProcPhaseTimeBaseTracker(phase_enum_2_phase_string(phase_number), phase_number, phase_times) { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
158 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
159 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
160 |
RefProcPhaseTimeTracker::~RefProcPhaseTimeTracker() { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
161 |
double elapsed = elapsed_time(); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
162 |
phase_times()->set_phase_time_ms(_phase_number, elapsed); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
163 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
164 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
165 |
RefProcTotalPhaseTimesTracker::RefProcTotalPhaseTimesTracker(ReferenceProcessor::RefProcPhases phase_number, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
166 |
ReferenceProcessorPhaseTimes* phase_times, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
167 |
ReferenceProcessor* rp) : |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
168 |
_rp(rp), RefProcPhaseTimeBaseTracker(phase_enum_2_phase_string(phase_number), phase_number, phase_times) { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
169 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
170 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
171 |
RefProcTotalPhaseTimesTracker::~RefProcTotalPhaseTimesTracker() { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
172 |
double elapsed = elapsed_time(); |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
173 |
phase_times()->set_phase_time_ms(_phase_number, elapsed); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
174 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
175 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
176 |
ReferenceProcessorPhaseTimes::ReferenceProcessorPhaseTimes(GCTimer* gc_timer, uint max_gc_threads) : |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
177 |
_gc_timer(gc_timer), _processing_is_mt(false) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
178 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
179 |
for (uint i = 0; i < ReferenceProcessor::RefSubPhaseMax; i++) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
180 |
_sub_phases_worker_time_sec[i] = new WorkerDataArray<double>(max_gc_threads, SubPhasesParWorkTitle[i]); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
181 |
} |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
182 |
_phase2_worker_time_sec = new WorkerDataArray<double>(max_gc_threads, Phase2ParWorkTitle); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
183 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
184 |
reset(); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
185 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
186 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
187 |
inline int ref_type_2_index(ReferenceType ref_type) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
188 |
return ref_type - REF_SOFT; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
189 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
190 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
191 |
WorkerDataArray<double>* ReferenceProcessorPhaseTimes::sub_phase_worker_time_sec(ReferenceProcessor::RefProcSubPhases sub_phase) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
192 |
ASSERT_SUB_PHASE(sub_phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
193 |
return _sub_phases_worker_time_sec[sub_phase]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
194 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
195 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
196 |
double ReferenceProcessorPhaseTimes::phase_time_ms(ReferenceProcessor::RefProcPhases phase) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
197 |
ASSERT_PHASE(phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
198 |
return _phases_time_ms[phase]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
199 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
200 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
201 |
void ReferenceProcessorPhaseTimes::set_phase_time_ms(ReferenceProcessor::RefProcPhases phase, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
202 |
double phase_time_ms) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
203 |
ASSERT_PHASE(phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
204 |
_phases_time_ms[phase] = phase_time_ms; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
205 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
206 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
207 |
void ReferenceProcessorPhaseTimes::reset() { |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
208 |
for (int i = 0; i < ReferenceProcessor::RefSubPhaseMax; i++) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
209 |
_sub_phases_worker_time_sec[i]->reset(); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
210 |
_sub_phases_total_time_ms[i] = uninitialized(); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
211 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
212 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
213 |
for (int i = 0; i < ReferenceProcessor::RefPhaseMax; i++) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
214 |
_phases_time_ms[i] = uninitialized(); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
215 |
_balance_queues_time_ms[i] = uninitialized(); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
216 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
217 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
218 |
_phase2_worker_time_sec->reset(); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
219 |
|
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
220 |
for (int i = 0; i < number_of_subclasses_of_ref; i++) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
221 |
_ref_cleared[i] = 0; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
222 |
_ref_discovered[i] = 0; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
223 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
224 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
225 |
_total_time_ms = uninitialized(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
226 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
227 |
_processing_is_mt = false; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
228 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
229 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
230 |
ReferenceProcessorPhaseTimes::~ReferenceProcessorPhaseTimes() { |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
231 |
for (int i = 0; i < ReferenceProcessor::RefSubPhaseMax; i++) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
232 |
delete _sub_phases_worker_time_sec[i]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
233 |
} |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
234 |
delete _phase2_worker_time_sec; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
235 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
236 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
237 |
double ReferenceProcessorPhaseTimes::sub_phase_total_time_ms(ReferenceProcessor::RefProcSubPhases sub_phase) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
238 |
ASSERT_SUB_PHASE(sub_phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
239 |
return _sub_phases_total_time_ms[sub_phase]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
240 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
241 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
242 |
void ReferenceProcessorPhaseTimes::set_sub_phase_total_phase_time_ms(ReferenceProcessor::RefProcSubPhases sub_phase, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
243 |
double time_ms) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
244 |
ASSERT_SUB_PHASE(sub_phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
245 |
_sub_phases_total_time_ms[sub_phase] = time_ms; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
246 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
247 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
248 |
void ReferenceProcessorPhaseTimes::add_ref_cleared(ReferenceType ref_type, size_t count) { |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
249 |
ASSERT_REF_TYPE(ref_type); |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
250 |
Atomic::add(count, &_ref_cleared[ref_type_2_index(ref_type)]); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
251 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
252 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
253 |
void ReferenceProcessorPhaseTimes::set_ref_discovered(ReferenceType ref_type, size_t count) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
254 |
ASSERT_REF_TYPE(ref_type); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
255 |
_ref_discovered[ref_type_2_index(ref_type)] = count; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
256 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
257 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
258 |
double ReferenceProcessorPhaseTimes::balance_queues_time_ms(ReferenceProcessor::RefProcPhases phase) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
259 |
ASSERT_PHASE(phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
260 |
return _balance_queues_time_ms[phase]; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
261 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
262 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
263 |
void ReferenceProcessorPhaseTimes::set_balance_queues_time_ms(ReferenceProcessor::RefProcPhases phase, double time_ms) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
264 |
ASSERT_PHASE(phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
265 |
_balance_queues_time_ms[phase] = time_ms; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
266 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
267 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
268 |
#define TIME_FORMAT "%.1lfms" |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
269 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
270 |
void ReferenceProcessorPhaseTimes::print_all_references(uint base_indent, bool print_total) const { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
271 |
if (print_total) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
272 |
LogTarget(Debug, gc, phases, ref) lt; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
273 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
274 |
if (lt.is_enabled()) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
275 |
LogStream ls(lt); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
276 |
ls.print_cr("%s%s: " TIME_FORMAT, |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
277 |
Indents[base_indent], "Reference Processing", total_time_ms()); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
278 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
279 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
280 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
281 |
uint next_indent = base_indent + 1; |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
282 |
print_phase(ReferenceProcessor::RefPhase1, next_indent); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
283 |
print_phase(ReferenceProcessor::RefPhase2, next_indent); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
284 |
print_phase(ReferenceProcessor::RefPhase3, next_indent); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
285 |
print_phase(ReferenceProcessor::RefPhase4, next_indent); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
286 |
|
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
287 |
print_reference(REF_SOFT, next_indent); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
288 |
print_reference(REF_WEAK, next_indent); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
289 |
print_reference(REF_FINAL, next_indent); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
290 |
print_reference(REF_PHANTOM, next_indent); |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
291 |
|
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
292 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
293 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
294 |
void ReferenceProcessorPhaseTimes::print_reference(ReferenceType ref_type, uint base_indent) const { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
295 |
LogTarget(Debug, gc, phases, ref) lt; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
296 |
|
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
297 |
if (lt.is_enabled()) { |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
298 |
LogStream ls(lt); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
299 |
ResourceMark rm; |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
300 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
301 |
ls.print_cr("%s%s:", Indents[base_indent], ref_type_2_string(ref_type)); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
302 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
303 |
uint const next_indent = base_indent + 1; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
304 |
int const ref_type_index = ref_type_2_index(ref_type); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
305 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
306 |
ls.print_cr("%sDiscovered: " SIZE_FORMAT, Indents[next_indent], _ref_discovered[ref_type_index]); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
307 |
ls.print_cr("%sCleared: " SIZE_FORMAT, Indents[next_indent], _ref_cleared[ref_type_index]); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
308 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
309 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
310 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
311 |
void ReferenceProcessorPhaseTimes::print_phase(ReferenceProcessor::RefProcPhases phase, uint indent) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
312 |
double phase_time = phase_time_ms(phase); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
313 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
314 |
if (phase_time == uninitialized()) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
315 |
return; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
316 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
317 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
318 |
LogTarget(Debug, gc, phases, ref) lt; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
319 |
LogStream ls(lt); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
320 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
321 |
ls.print_cr("%s%s%s " TIME_FORMAT, |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
322 |
Indents[indent], |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
323 |
phase_enum_2_phase_string(phase), |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
324 |
indent == 0 ? "" : ":", /* 0 indent logs don't need colon. */ |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
325 |
phase_time); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
326 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
327 |
LogTarget(Debug, gc, phases, ref) lt2; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
328 |
if (lt2.is_enabled()) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
329 |
LogStream ls(lt2); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
330 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
331 |
if (_processing_is_mt) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
332 |
print_balance_time(&ls, phase, indent + 1); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
333 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
334 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
335 |
switch (phase) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
336 |
case ReferenceProcessor::RefPhase1: |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
337 |
print_sub_phase(&ls, ReferenceProcessor::SoftRefSubPhase1, indent + 1); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
338 |
break; |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
339 |
case ReferenceProcessor::RefPhase2: |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
340 |
print_sub_phase(&ls, ReferenceProcessor::SoftRefSubPhase2, indent + 1); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
341 |
print_sub_phase(&ls, ReferenceProcessor::WeakRefSubPhase2, indent + 1); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
342 |
print_sub_phase(&ls, ReferenceProcessor::FinalRefSubPhase2, indent + 1); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
343 |
break; |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
344 |
case ReferenceProcessor::RefPhase3: |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
345 |
print_sub_phase(&ls, ReferenceProcessor::FinalRefSubPhase3, indent + 1); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
346 |
break; |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
347 |
case ReferenceProcessor::RefPhase4: |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
348 |
print_sub_phase(&ls, ReferenceProcessor::PhantomRefSubPhase4, indent + 1); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
349 |
break; |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
350 |
default: |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
351 |
ShouldNotReachHere(); |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
352 |
} |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
353 |
if (phase == ReferenceProcessor::RefPhase2) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
354 |
print_worker_time(&ls, _phase2_worker_time_sec, Phase2SerWorkTitle, indent + 1); |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
355 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
356 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
357 |
} |
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
358 |
|
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
359 |
void ReferenceProcessorPhaseTimes::print_balance_time(LogStream* ls, ReferenceProcessor::RefProcPhases phase, uint indent) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
360 |
double balance_time = balance_queues_time_ms(phase); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
361 |
if (balance_time != uninitialized()) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
362 |
ls->print_cr("%s%s " TIME_FORMAT, Indents[indent], "Balance queues:", balance_time); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
363 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
364 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
365 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
366 |
void ReferenceProcessorPhaseTimes::print_sub_phase(LogStream* ls, ReferenceProcessor::RefProcSubPhases sub_phase, uint indent) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
367 |
print_worker_time(ls, _sub_phases_worker_time_sec[sub_phase], SubPhasesSerWorkTitle[sub_phase], indent); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
368 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
369 |
|
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
370 |
void ReferenceProcessorPhaseTimes::print_worker_time(LogStream* ls, WorkerDataArray<double>* worker_time, const char* ser_title, uint indent) const { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
371 |
ls->print("%s", Indents[indent]); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
372 |
if (_processing_is_mt) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
373 |
worker_time->print_summary_on(ls, true); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
374 |
LogTarget(Trace, gc, phases, task) lt; |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
375 |
if (lt.is_enabled()) { |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
376 |
LogStream ls2(lt); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
377 |
ls2.print("%s", Indents[indent]); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
378 |
worker_time->print_details_on(&ls2); |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
379 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
380 |
} else { |
50606
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
381 |
if (worker_time->get(0) != uninitialized()) { |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
382 |
ls->print_cr("%s " TIME_FORMAT, |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
383 |
ser_title, |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
384 |
worker_time->get(0) * MILLIUNITS); |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
385 |
} else { |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
386 |
ls->print_cr("%s skipped", ser_title); |
8f1d5d706bdd
8043575: Dynamically parallelize reference processing work
tschatzl
parents:
50605
diff
changeset
|
387 |
} |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
388 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
389 |
} |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
390 |
|
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
391 |
#undef ASSERT_REF_TYPE |
50605
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
392 |
#undef ASSERT_SUB_PHASE |
7f63c74f0974
8202845: Refactor reference processing for improved parallelism
tschatzl
parents:
50049
diff
changeset
|
393 |
#undef ASSERT_PHASE |
46795
623a5e42deb6
8173335: Improve logging for j.l.ref.reference processing
sangheki
parents:
diff
changeset
|
394 |
#undef TIME_FORMAT |