| 1 | /* |
| 2 | * Copyright (c) 2018, Oracle and/or its affiliates. All rights reserved. |
| 3 | * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. |
| 4 | * |
| 5 | * This code is free software; you can redistribute it and/or modify it |
| 6 | * under the terms of the GNU General Public License version 2 only, as |
| 7 | * published by the Free Software Foundation. |
| 8 | * |
| 9 | * This code is distributed in the hope that it will be useful, but WITHOUT |
| 10 | * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or |
| 11 | * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License |
| 12 | * version 2 for more details (a copy is included in the LICENSE file that |
| 13 | * accompanied this code). |
| 14 | * |
| 15 | * You should have received a copy of the GNU General Public License version |
| 16 | * 2 along with this work; if not, write to the Free Software Foundation, |
| 17 | * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. |
| 18 | * |
| 19 | * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA |
| 20 | * or visit www.oracle.com if you need additional information or have any |
| 21 | * questions. |
| 22 | */ |
| 23 | |
| 24 | #include "precompiled.hpp" |
| 25 | #include "gc/shared/oopStorage.inline.hpp" |
| 26 | #include "gc/shared/oopStorageParState.inline.hpp" |
| 27 | #include "gc/shared/workgroup.hpp" |
| 28 | #include "logging/log.hpp" |
| 29 | #include "logging/logConfiguration.hpp" |
| 30 | #include "memory/allocation.inline.hpp" |
| 31 | #include "memory/iterator.inline.hpp" |
| 32 | #include "runtime/interfaceSupport.inline.hpp" |
| 33 | #include "runtime/os.hpp" |
| 34 | #include "runtime/thread.hpp" |
| 35 | #include "runtime/vmOperations.hpp" |
| 36 | #include "runtime/vmThread.hpp" |
| 37 | #include "utilities/debug.hpp" |
| 38 | #include "utilities/ostream.hpp" |
| 39 | #include "utilities/ticks.hpp" |
| 40 | |
| 41 | #include "unittest.hpp" |
| 42 | |
| 43 | // This "test" doesn't really verify much. Rather, it's mostly a |
| 44 | // microbenchmark for OopStorage parallel iteration. It executes |
| 45 | // parallel iteration with varying numbers of threads on an storage |
| 46 | // object containing a large number of entries, and logs some stats |
| 47 | // about the distribution and performance of the iteration. |
| 48 | |
| 49 | const uint _max_workers = 10; |
| 50 | static uint _num_workers = 0; |
| 51 | const size_t _storage_entries = 1000000; |
| 52 | |
| 53 | class OopStorageParIterPerf : public ::testing::Test { |
| 54 | public: |
| 55 | OopStorageParIterPerf(); |
| 56 | ~OopStorageParIterPerf(); |
| 57 | |
| 58 | WorkGang* workers() const; |
| 59 | |
| 60 | class VM_ParStateTime; |
| 61 | class Task; |
| 62 | class Closure; |
| 63 | |
| 64 | Tickspan run_task(Task* task, uint nthreads); |
| 65 | void show_task(const Task* task, Tickspan duration, uint nthreads); |
| 66 | void run_test(uint nthreads); |
| 67 | |
| 68 | static WorkGang* _workers; |
| 69 | |
| 70 | static const int _active_rank = Mutex::leaf - 1; |
| 71 | static const int _allocate_rank = Mutex::leaf; |
| 72 | |
| 73 | Mutex _allocate_mutex; |
| 74 | Mutex _active_mutex; |
| 75 | OopStorage _storage; |
| 76 | oop* _entries[_storage_entries]; |
| 77 | }; |
| 78 | |
| 79 | WorkGang* OopStorageParIterPerf::_workers = NULL; |
| 80 | |
| 81 | WorkGang* OopStorageParIterPerf::workers() const { |
| 82 | if (_workers == NULL) { |
| 83 | WorkGang* wg = new WorkGang("OopStorageParIterPerf workers" , |
| 84 | _num_workers, |
| 85 | false, |
| 86 | false); |
| 87 | wg->initialize_workers(); |
| 88 | wg->update_active_workers(_num_workers); |
| 89 | _workers = wg; |
| 90 | } |
| 91 | return _workers; |
| 92 | } |
| 93 | |
| 94 | OopStorageParIterPerf::OopStorageParIterPerf() : |
| 95 | _allocate_mutex(_allocate_rank, |
| 96 | "test_OopStorage_parperf_allocate" , |
| 97 | false, |
| 98 | Mutex::_safepoint_check_never), |
| 99 | _active_mutex(_active_rank, |
| 100 | "test_OopStorage_parperf_active" , |
| 101 | false, |
| 102 | Mutex::_safepoint_check_never), |
| 103 | _storage("Test Storage" , &_allocate_mutex, &_active_mutex) |
| 104 | { |
| 105 | for (size_t i = 0; i < _storage_entries; ++i) { |
| 106 | _entries[i] = _storage.allocate(); |
| 107 | } |
| 108 | _num_workers = MIN2(_max_workers, (uint)os::processor_count()); |
| 109 | } |
| 110 | |
| 111 | OopStorageParIterPerf::~OopStorageParIterPerf() { |
| 112 | _storage.release(_entries, ARRAY_SIZE(_entries)); |
| 113 | } |
| 114 | |
| 115 | class OopStorageParIterPerf::VM_ParStateTime : public VM_GTestExecuteAtSafepoint { |
| 116 | public: |
| 117 | VM_ParStateTime(WorkGang* workers, AbstractGangTask* task, uint nthreads) : |
| 118 | _workers(workers), _task(task), _nthreads(nthreads) |
| 119 | {} |
| 120 | |
| 121 | void doit() { |
| 122 | _workers->run_task(_task, _nthreads); |
| 123 | } |
| 124 | |
| 125 | private: |
| 126 | WorkGang* _workers; |
| 127 | AbstractGangTask* _task; |
| 128 | uint _nthreads; |
| 129 | }; |
| 130 | |
| 131 | class OopStorageParIterPerf::Task : public AbstractGangTask { |
| 132 | typedef OopStorage::ParState<false, false> StateType; |
| 133 | |
| 134 | Tickspan* _worker_times; |
| 135 | StateType _state; |
| 136 | OopClosure* _closure; |
| 137 | |
| 138 | public: |
| 139 | Task(OopStorage* storage, OopClosure* closure, uint nthreads) : |
| 140 | AbstractGangTask("OopStorageParIterPerf::Task" ), |
| 141 | _worker_times(NULL), |
| 142 | _state(storage, nthreads), |
| 143 | _closure(closure) |
| 144 | { |
| 145 | Tickspan* wtimes = NEW_C_HEAP_ARRAY(Tickspan, _num_workers, mtInternal); |
| 146 | for (uint i = 0; i < _num_workers; ++i) { |
| 147 | new (&wtimes[i]) Tickspan(); |
| 148 | } |
| 149 | _worker_times = wtimes; |
| 150 | } |
| 151 | |
| 152 | ~Task() { |
| 153 | FREE_C_HEAP_ARRAY(Tickspan, _worker_times); |
| 154 | } |
| 155 | |
| 156 | virtual void work(uint worker_id) { |
| 157 | Ticks start_time = Ticks::now(); |
| 158 | _state.oops_do(_closure); |
| 159 | _worker_times[worker_id] = Ticks::now() - start_time; |
| 160 | } |
| 161 | |
| 162 | const Tickspan* worker_times() const { return _worker_times; } |
| 163 | }; |
| 164 | |
| 165 | class OopStorageParIterPerf::Closure : public OopClosure { |
| 166 | public: |
| 167 | virtual void do_oop(oop* p) { guarantee(*p == NULL, "expected NULL" ); } |
| 168 | virtual void do_oop(narrowOop* p) { ShouldNotReachHere(); } |
| 169 | }; |
| 170 | |
| 171 | Tickspan OopStorageParIterPerf::run_task(Task* task, uint nthreads) { |
| 172 | tty->print_cr("Running test with %u threads" , nthreads); |
| 173 | VM_ParStateTime op(workers(), task, nthreads); |
| 174 | ThreadInVMfromNative invm(JavaThread::current()); |
| 175 | Ticks start_time = Ticks::now(); |
| 176 | VMThread::execute(&op); |
| 177 | return Ticks::now() - start_time; |
| 178 | } |
| 179 | |
| 180 | void OopStorageParIterPerf::show_task(const Task* task, Tickspan duration, uint nthreads) { |
| 181 | tty->print_cr("Run test with %u threads: " JLONG_FORMAT, nthreads, duration.value()); |
| 182 | const Tickspan* wtimes = task->worker_times(); |
| 183 | for (uint i = 0; i < _num_workers; ++i) { |
| 184 | if (wtimes[i] != Tickspan()) { |
| 185 | tty->print_cr(" %u: " JLONG_FORMAT, i, wtimes[i].value()); |
| 186 | } |
| 187 | } |
| 188 | tty->cr(); |
| 189 | } |
| 190 | |
| 191 | void OopStorageParIterPerf::run_test(uint nthreads) { |
| 192 | if (nthreads <= _num_workers) { |
| 193 | SCOPED_TRACE(err_msg("Running test with %u threads" , nthreads).buffer()); |
| 194 | Closure closure; |
| 195 | Task task(&_storage, &closure, nthreads); |
| 196 | Tickspan t = run_task(&task, nthreads); |
| 197 | show_task(&task, t, nthreads); |
| 198 | } |
| 199 | } |
| 200 | |
| 201 | TEST_VM_F(OopStorageParIterPerf, test) { |
| 202 | // Enable additional interesting logging. |
| 203 | #define TEST_TAGS oopstorage, blocks, stats |
| 204 | // There isn't an obvious way to capture the old log level so it |
| 205 | // can be restored here, so just use Warning as the "default". |
| 206 | LogLevelType old_level = LogLevel::Warning; |
| 207 | if (log_is_enabled(Debug, TEST_TAGS)) { |
| 208 | old_level = LogLevel::Debug; |
| 209 | } else if (log_is_enabled(Info, TEST_TAGS)) { |
| 210 | old_level = LogLevel::Info; |
| 211 | } |
| 212 | bool debug_enabled = old_level == LogLevel::Debug; |
| 213 | if (!debug_enabled) { |
| 214 | LogConfiguration::configure_stdout(LogLevel::Debug, true, LOG_TAGS(TEST_TAGS)); |
| 215 | } |
| 216 | |
| 217 | run_test(1); |
| 218 | run_test(2); |
| 219 | run_test(3); |
| 220 | run_test(4); |
| 221 | run_test(6); |
| 222 | run_test(8); |
| 223 | run_test(10); |
| 224 | |
| 225 | if (!debug_enabled) { |
| 226 | LogConfiguration::configure_stdout(old_level, true, LOG_TAGS(TEST_TAGS)); |
| 227 | } |
| 228 | } |
| 229 | |