Fri, 12 Feb 2010 15:27:36 -0800
Merge
duke@435 | 1 | /* |
xdono@631 | 2 | * Copyright 1997-2008 Sun Microsystems, Inc. All Rights Reserved. |
duke@435 | 3 | * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER. |
duke@435 | 4 | * |
duke@435 | 5 | * This code is free software; you can redistribute it and/or modify it |
duke@435 | 6 | * under the terms of the GNU General Public License version 2 only, as |
duke@435 | 7 | * published by the Free Software Foundation. |
duke@435 | 8 | * |
duke@435 | 9 | * This code is distributed in the hope that it will be useful, but WITHOUT |
duke@435 | 10 | * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or |
duke@435 | 11 | * FITNESS FOR A PARTICULAR PURPOSE. See the GNU General Public License |
duke@435 | 12 | * version 2 for more details (a copy is included in the LICENSE file that |
duke@435 | 13 | * accompanied this code). |
duke@435 | 14 | * |
duke@435 | 15 | * You should have received a copy of the GNU General Public License version |
duke@435 | 16 | * 2 along with this work; if not, write to the Free Software Foundation, |
duke@435 | 17 | * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA. |
duke@435 | 18 | * |
duke@435 | 19 | * Please contact Sun Microsystems, Inc., 4150 Network Circle, Santa Clara, |
duke@435 | 20 | * CA 95054 USA or visit www.sun.com if you need additional information or |
duke@435 | 21 | * have any questions. |
duke@435 | 22 | * |
duke@435 | 23 | */ |
duke@435 | 24 | |
duke@435 | 25 | # include "incls/_precompiled.incl" |
duke@435 | 26 | # include "incls/_fprofiler.cpp.incl" |
duke@435 | 27 | |
duke@435 | 28 | // Static fields of FlatProfiler |
duke@435 | 29 | int FlatProfiler::received_gc_ticks = 0; |
duke@435 | 30 | int FlatProfiler::vm_operation_ticks = 0; |
duke@435 | 31 | int FlatProfiler::threads_lock_ticks = 0; |
duke@435 | 32 | int FlatProfiler::class_loader_ticks = 0; |
duke@435 | 33 | int FlatProfiler::extra_ticks = 0; |
duke@435 | 34 | int FlatProfiler::blocked_ticks = 0; |
duke@435 | 35 | int FlatProfiler::deopt_ticks = 0; |
duke@435 | 36 | int FlatProfiler::unknown_ticks = 0; |
duke@435 | 37 | int FlatProfiler::interpreter_ticks = 0; |
duke@435 | 38 | int FlatProfiler::compiler_ticks = 0; |
duke@435 | 39 | int FlatProfiler::received_ticks = 0; |
duke@435 | 40 | int FlatProfiler::delivered_ticks = 0; |
duke@435 | 41 | int* FlatProfiler::bytecode_ticks = NULL; |
duke@435 | 42 | int* FlatProfiler::bytecode_ticks_stub = NULL; |
duke@435 | 43 | int FlatProfiler::all_int_ticks = 0; |
duke@435 | 44 | int FlatProfiler::all_comp_ticks = 0; |
duke@435 | 45 | int FlatProfiler::all_ticks = 0; |
duke@435 | 46 | bool FlatProfiler::full_profile_flag = false; |
duke@435 | 47 | ThreadProfiler* FlatProfiler::thread_profiler = NULL; |
duke@435 | 48 | ThreadProfiler* FlatProfiler::vm_thread_profiler = NULL; |
duke@435 | 49 | FlatProfilerTask* FlatProfiler::task = NULL; |
duke@435 | 50 | elapsedTimer FlatProfiler::timer; |
duke@435 | 51 | int FlatProfiler::interval_ticks_previous = 0; |
duke@435 | 52 | IntervalData* FlatProfiler::interval_data = NULL; |
duke@435 | 53 | |
duke@435 | 54 | ThreadProfiler::ThreadProfiler() { |
duke@435 | 55 | // Space for the ProfilerNodes |
duke@435 | 56 | const int area_size = 1 * ProfilerNodeSize * 1024; |
duke@435 | 57 | area_bottom = AllocateHeap(area_size, "fprofiler"); |
duke@435 | 58 | area_top = area_bottom; |
duke@435 | 59 | area_limit = area_bottom + area_size; |
duke@435 | 60 | |
duke@435 | 61 | // ProfilerNode pointer table |
duke@435 | 62 | table = NEW_C_HEAP_ARRAY(ProfilerNode*, table_size); |
duke@435 | 63 | initialize(); |
duke@435 | 64 | engaged = false; |
duke@435 | 65 | } |
duke@435 | 66 | |
duke@435 | 67 | ThreadProfiler::~ThreadProfiler() { |
duke@435 | 68 | FreeHeap(area_bottom); |
duke@435 | 69 | area_bottom = NULL; |
duke@435 | 70 | area_top = NULL; |
duke@435 | 71 | area_limit = NULL; |
duke@435 | 72 | FreeHeap(table); |
duke@435 | 73 | table = NULL; |
duke@435 | 74 | } |
duke@435 | 75 | |
duke@435 | 76 | // Statics for ThreadProfiler |
duke@435 | 77 | int ThreadProfiler::table_size = 1024; |
duke@435 | 78 | |
duke@435 | 79 | int ThreadProfiler::entry(int value) { |
duke@435 | 80 | value = (value > 0) ? value : -value; |
duke@435 | 81 | return value % table_size; |
duke@435 | 82 | } |
duke@435 | 83 | |
duke@435 | 84 | ThreadProfilerMark::ThreadProfilerMark(ThreadProfilerMark::Region r) { |
duke@435 | 85 | _r = r; |
duke@435 | 86 | _pp = NULL; |
duke@435 | 87 | assert(((r > ThreadProfilerMark::noRegion) && (r < ThreadProfilerMark::maxRegion)), "ThreadProfilerMark::Region out of bounds"); |
duke@435 | 88 | Thread* tp = Thread::current(); |
duke@435 | 89 | if (tp != NULL && tp->is_Java_thread()) { |
duke@435 | 90 | JavaThread* jtp = (JavaThread*) tp; |
duke@435 | 91 | ThreadProfiler* pp = jtp->get_thread_profiler(); |
duke@435 | 92 | _pp = pp; |
duke@435 | 93 | if (pp != NULL) { |
duke@435 | 94 | pp->region_flag[r] = true; |
duke@435 | 95 | } |
duke@435 | 96 | } |
duke@435 | 97 | } |
duke@435 | 98 | |
duke@435 | 99 | ThreadProfilerMark::~ThreadProfilerMark() { |
duke@435 | 100 | if (_pp != NULL) { |
duke@435 | 101 | _pp->region_flag[_r] = false; |
duke@435 | 102 | } |
duke@435 | 103 | _pp = NULL; |
duke@435 | 104 | } |
duke@435 | 105 | |
duke@435 | 106 | // Random other statics |
duke@435 | 107 | static const int col1 = 2; // position of output column 1 |
duke@435 | 108 | static const int col2 = 11; // position of output column 2 |
duke@435 | 109 | static const int col3 = 25; // position of output column 3 |
duke@435 | 110 | static const int col4 = 55; // position of output column 4 |
duke@435 | 111 | |
duke@435 | 112 | |
duke@435 | 113 | // Used for detailed profiling of nmethods. |
duke@435 | 114 | class PCRecorder : AllStatic { |
duke@435 | 115 | private: |
duke@435 | 116 | static int* counters; |
duke@435 | 117 | static address base; |
duke@435 | 118 | enum { |
duke@435 | 119 | bucket_size = 16 |
duke@435 | 120 | }; |
duke@435 | 121 | static int index_for(address pc) { return (pc - base)/bucket_size; } |
duke@435 | 122 | static address pc_for(int index) { return base + (index * bucket_size); } |
duke@435 | 123 | static int size() { |
duke@435 | 124 | return ((int)CodeCache::max_capacity())/bucket_size * BytesPerWord; |
duke@435 | 125 | } |
duke@435 | 126 | public: |
duke@435 | 127 | static address bucket_start_for(address pc) { |
duke@435 | 128 | if (counters == NULL) return NULL; |
duke@435 | 129 | return pc_for(index_for(pc)); |
duke@435 | 130 | } |
duke@435 | 131 | static int bucket_count_for(address pc) { return counters[index_for(pc)]; } |
duke@435 | 132 | static void init(); |
duke@435 | 133 | static void record(address pc); |
duke@435 | 134 | static void print(); |
duke@435 | 135 | static void print_blobs(CodeBlob* cb); |
duke@435 | 136 | }; |
duke@435 | 137 | |
duke@435 | 138 | int* PCRecorder::counters = NULL; |
duke@435 | 139 | address PCRecorder::base = NULL; |
duke@435 | 140 | |
duke@435 | 141 | void PCRecorder::init() { |
duke@435 | 142 | MutexLockerEx lm(CodeCache_lock, Mutex::_no_safepoint_check_flag); |
duke@435 | 143 | int s = size(); |
duke@435 | 144 | counters = NEW_C_HEAP_ARRAY(int, s); |
duke@435 | 145 | for (int index = 0; index < s; index++) { |
duke@435 | 146 | counters[index] = 0; |
duke@435 | 147 | } |
duke@435 | 148 | base = CodeCache::first_address(); |
duke@435 | 149 | } |
duke@435 | 150 | |
duke@435 | 151 | void PCRecorder::record(address pc) { |
duke@435 | 152 | if (counters == NULL) return; |
duke@435 | 153 | assert(CodeCache::contains(pc), "must be in CodeCache"); |
duke@435 | 154 | counters[index_for(pc)]++; |
duke@435 | 155 | } |
duke@435 | 156 | |
duke@435 | 157 | |
duke@435 | 158 | address FlatProfiler::bucket_start_for(address pc) { |
duke@435 | 159 | return PCRecorder::bucket_start_for(pc); |
duke@435 | 160 | } |
duke@435 | 161 | |
duke@435 | 162 | int FlatProfiler::bucket_count_for(address pc) { |
duke@435 | 163 | return PCRecorder::bucket_count_for(pc); |
duke@435 | 164 | } |
duke@435 | 165 | |
duke@435 | 166 | void PCRecorder::print() { |
duke@435 | 167 | if (counters == NULL) return; |
duke@435 | 168 | |
duke@435 | 169 | tty->cr(); |
duke@435 | 170 | tty->print_cr("Printing compiled methods with PC buckets having more than %d ticks", ProfilerPCTickThreshold); |
duke@435 | 171 | tty->print_cr("==================================================================="); |
duke@435 | 172 | tty->cr(); |
duke@435 | 173 | |
duke@435 | 174 | GrowableArray<CodeBlob*>* candidates = new GrowableArray<CodeBlob*>(20); |
duke@435 | 175 | |
duke@435 | 176 | |
duke@435 | 177 | int s; |
duke@435 | 178 | { |
duke@435 | 179 | MutexLockerEx lm(CodeCache_lock, Mutex::_no_safepoint_check_flag); |
duke@435 | 180 | s = size(); |
duke@435 | 181 | } |
duke@435 | 182 | |
duke@435 | 183 | for (int index = 0; index < s; index++) { |
duke@435 | 184 | int count = counters[index]; |
duke@435 | 185 | if (count > ProfilerPCTickThreshold) { |
duke@435 | 186 | address pc = pc_for(index); |
duke@435 | 187 | CodeBlob* cb = CodeCache::find_blob_unsafe(pc); |
duke@435 | 188 | if (cb != NULL && candidates->find(cb) < 0) { |
duke@435 | 189 | candidates->push(cb); |
duke@435 | 190 | } |
duke@435 | 191 | } |
duke@435 | 192 | } |
duke@435 | 193 | for (int i = 0; i < candidates->length(); i++) { |
duke@435 | 194 | print_blobs(candidates->at(i)); |
duke@435 | 195 | } |
duke@435 | 196 | } |
duke@435 | 197 | |
duke@435 | 198 | void PCRecorder::print_blobs(CodeBlob* cb) { |
duke@435 | 199 | if (cb != NULL) { |
duke@435 | 200 | cb->print(); |
duke@435 | 201 | if (cb->is_nmethod()) { |
duke@435 | 202 | ((nmethod*)cb)->print_code(); |
duke@435 | 203 | } |
duke@435 | 204 | tty->cr(); |
duke@435 | 205 | } else { |
duke@435 | 206 | tty->print_cr("stub code"); |
duke@435 | 207 | } |
duke@435 | 208 | } |
duke@435 | 209 | |
duke@435 | 210 | class tick_counter { // holds tick info for one node |
duke@435 | 211 | public: |
duke@435 | 212 | int ticks_in_code; |
duke@435 | 213 | int ticks_in_native; |
duke@435 | 214 | |
duke@435 | 215 | tick_counter() { ticks_in_code = ticks_in_native = 0; } |
duke@435 | 216 | tick_counter(int code, int native) { ticks_in_code = code; ticks_in_native = native; } |
duke@435 | 217 | |
duke@435 | 218 | int total() const { |
duke@435 | 219 | return (ticks_in_code + ticks_in_native); |
duke@435 | 220 | } |
duke@435 | 221 | |
duke@435 | 222 | void add(tick_counter* a) { |
duke@435 | 223 | ticks_in_code += a->ticks_in_code; |
duke@435 | 224 | ticks_in_native += a->ticks_in_native; |
duke@435 | 225 | } |
duke@435 | 226 | |
duke@435 | 227 | void update(TickPosition where) { |
duke@435 | 228 | switch(where) { |
duke@435 | 229 | case tp_code: ticks_in_code++; break; |
duke@435 | 230 | case tp_native: ticks_in_native++; break; |
duke@435 | 231 | } |
duke@435 | 232 | } |
duke@435 | 233 | |
duke@435 | 234 | void print_code(outputStream* st, int total_ticks) { |
duke@435 | 235 | st->print("%5.1f%% %5d ", total() * 100.0 / total_ticks, ticks_in_code); |
duke@435 | 236 | } |
duke@435 | 237 | |
duke@435 | 238 | void print_native(outputStream* st) { |
duke@435 | 239 | st->print(" + %5d ", ticks_in_native); |
duke@435 | 240 | } |
duke@435 | 241 | }; |
duke@435 | 242 | |
duke@435 | 243 | class ProfilerNode { |
duke@435 | 244 | private: |
duke@435 | 245 | ProfilerNode* _next; |
duke@435 | 246 | public: |
duke@435 | 247 | tick_counter ticks; |
duke@435 | 248 | |
duke@435 | 249 | public: |
duke@435 | 250 | |
duke@435 | 251 | void* operator new(size_t size, ThreadProfiler* tp); |
duke@435 | 252 | void operator delete(void* p); |
duke@435 | 253 | |
duke@435 | 254 | ProfilerNode() { |
duke@435 | 255 | _next = NULL; |
duke@435 | 256 | } |
duke@435 | 257 | |
duke@435 | 258 | virtual ~ProfilerNode() { |
duke@435 | 259 | if (_next) |
duke@435 | 260 | delete _next; |
duke@435 | 261 | } |
duke@435 | 262 | |
duke@435 | 263 | void set_next(ProfilerNode* n) { _next = n; } |
duke@435 | 264 | ProfilerNode* next() { return _next; } |
duke@435 | 265 | |
duke@435 | 266 | void update(TickPosition where) { ticks.update(where);} |
duke@435 | 267 | int total_ticks() { return ticks.total(); } |
duke@435 | 268 | |
duke@435 | 269 | virtual bool is_interpreted() const { return false; } |
duke@435 | 270 | virtual bool is_compiled() const { return false; } |
duke@435 | 271 | virtual bool is_stub() const { return false; } |
duke@435 | 272 | virtual bool is_runtime_stub() const{ return false; } |
duke@435 | 273 | virtual void oops_do(OopClosure* f) = 0; |
duke@435 | 274 | |
duke@435 | 275 | virtual bool interpreted_match(methodOop m) const { return false; } |
duke@435 | 276 | virtual bool compiled_match(methodOop m ) const { return false; } |
duke@435 | 277 | virtual bool stub_match(methodOop m, const char* name) const { return false; } |
duke@435 | 278 | virtual bool adapter_match() const { return false; } |
duke@435 | 279 | virtual bool runtimeStub_match(const CodeBlob* stub, const char* name) const { return false; } |
duke@435 | 280 | virtual bool unknown_compiled_match(const CodeBlob* cb) const { return false; } |
duke@435 | 281 | |
duke@435 | 282 | static void print_title(outputStream* st) { |
duke@435 | 283 | st->print(" + native"); |
duke@435 | 284 | st->fill_to(col3); |
duke@435 | 285 | st->print("Method"); |
duke@435 | 286 | st->fill_to(col4); |
duke@435 | 287 | st->cr(); |
duke@435 | 288 | } |
duke@435 | 289 | |
duke@435 | 290 | static void print_total(outputStream* st, tick_counter* t, int total, const char* msg) { |
duke@435 | 291 | t->print_code(st, total); |
duke@435 | 292 | st->fill_to(col2); |
duke@435 | 293 | t->print_native(st); |
duke@435 | 294 | st->fill_to(col3); |
duke@435 | 295 | st->print(msg); |
duke@435 | 296 | st->cr(); |
duke@435 | 297 | } |
duke@435 | 298 | |
duke@435 | 299 | virtual methodOop method() = 0; |
duke@435 | 300 | |
duke@435 | 301 | virtual void print_method_on(outputStream* st) { |
duke@435 | 302 | int limit; |
duke@435 | 303 | int i; |
duke@435 | 304 | methodOop m = method(); |
duke@435 | 305 | symbolOop k = m->klass_name(); |
duke@435 | 306 | // Print the class name with dots instead of slashes |
duke@435 | 307 | limit = k->utf8_length(); |
duke@435 | 308 | for (i = 0 ; i < limit ; i += 1) { |
duke@435 | 309 | char c = (char) k->byte_at(i); |
duke@435 | 310 | if (c == '/') { |
duke@435 | 311 | c = '.'; |
duke@435 | 312 | } |
duke@435 | 313 | st->print("%c", c); |
duke@435 | 314 | } |
duke@435 | 315 | if (limit > 0) { |
duke@435 | 316 | st->print("."); |
duke@435 | 317 | } |
duke@435 | 318 | symbolOop n = m->name(); |
duke@435 | 319 | limit = n->utf8_length(); |
duke@435 | 320 | for (i = 0 ; i < limit ; i += 1) { |
duke@435 | 321 | char c = (char) n->byte_at(i); |
duke@435 | 322 | st->print("%c", c); |
duke@435 | 323 | } |
duke@435 | 324 | if( Verbose ) { |
duke@435 | 325 | // Disambiguate overloaded methods |
duke@435 | 326 | symbolOop sig = m->signature(); |
duke@435 | 327 | sig->print_symbol_on(st); |
duke@435 | 328 | } |
duke@435 | 329 | } |
duke@435 | 330 | |
duke@435 | 331 | virtual void print(outputStream* st, int total_ticks) { |
duke@435 | 332 | ticks.print_code(st, total_ticks); |
duke@435 | 333 | st->fill_to(col2); |
duke@435 | 334 | ticks.print_native(st); |
duke@435 | 335 | st->fill_to(col3); |
duke@435 | 336 | print_method_on(st); |
duke@435 | 337 | st->cr(); |
duke@435 | 338 | } |
duke@435 | 339 | |
duke@435 | 340 | // for hashing into the table |
duke@435 | 341 | static int hash(methodOop method) { |
duke@435 | 342 | // The point here is to try to make something fairly unique |
duke@435 | 343 | // out of the fields we can read without grabbing any locks |
duke@435 | 344 | // since the method may be locked when we need the hash. |
duke@435 | 345 | return ( |
duke@435 | 346 | method->code_size() ^ |
duke@435 | 347 | method->max_stack() ^ |
duke@435 | 348 | method->max_locals() ^ |
duke@435 | 349 | method->size_of_parameters()); |
duke@435 | 350 | } |
duke@435 | 351 | |
duke@435 | 352 | // for sorting |
duke@435 | 353 | static int compare(ProfilerNode** a, ProfilerNode** b) { |
duke@435 | 354 | return (*b)->total_ticks() - (*a)->total_ticks(); |
duke@435 | 355 | } |
duke@435 | 356 | }; |
duke@435 | 357 | |
duke@435 | 358 | void* ProfilerNode::operator new(size_t size, ThreadProfiler* tp){ |
duke@435 | 359 | void* result = (void*) tp->area_top; |
duke@435 | 360 | tp->area_top += size; |
duke@435 | 361 | |
duke@435 | 362 | if (tp->area_top > tp->area_limit) { |
duke@435 | 363 | fatal("flat profiler buffer overflow"); |
duke@435 | 364 | } |
duke@435 | 365 | return result; |
duke@435 | 366 | } |
duke@435 | 367 | |
duke@435 | 368 | void ProfilerNode::operator delete(void* p){ |
duke@435 | 369 | } |
duke@435 | 370 | |
duke@435 | 371 | class interpretedNode : public ProfilerNode { |
duke@435 | 372 | private: |
duke@435 | 373 | methodOop _method; |
duke@435 | 374 | public: |
duke@435 | 375 | interpretedNode(methodOop method, TickPosition where) : ProfilerNode() { |
duke@435 | 376 | _method = method; |
duke@435 | 377 | update(where); |
duke@435 | 378 | } |
duke@435 | 379 | |
duke@435 | 380 | bool is_interpreted() const { return true; } |
duke@435 | 381 | |
duke@435 | 382 | bool interpreted_match(methodOop m) const { |
duke@435 | 383 | return _method == m; |
duke@435 | 384 | } |
duke@435 | 385 | |
duke@435 | 386 | void oops_do(OopClosure* f) { |
duke@435 | 387 | f->do_oop((oop*)&_method); |
duke@435 | 388 | } |
duke@435 | 389 | |
duke@435 | 390 | methodOop method() { return _method; } |
duke@435 | 391 | |
duke@435 | 392 | static void print_title(outputStream* st) { |
duke@435 | 393 | st->fill_to(col1); |
duke@435 | 394 | st->print("%11s", "Interpreted"); |
duke@435 | 395 | ProfilerNode::print_title(st); |
duke@435 | 396 | } |
duke@435 | 397 | |
duke@435 | 398 | void print(outputStream* st, int total_ticks) { |
duke@435 | 399 | ProfilerNode::print(st, total_ticks); |
duke@435 | 400 | } |
duke@435 | 401 | |
duke@435 | 402 | void print_method_on(outputStream* st) { |
duke@435 | 403 | ProfilerNode::print_method_on(st); |
duke@435 | 404 | if (Verbose) method()->invocation_counter()->print_short(); |
duke@435 | 405 | } |
duke@435 | 406 | }; |
duke@435 | 407 | |
duke@435 | 408 | class compiledNode : public ProfilerNode { |
duke@435 | 409 | private: |
duke@435 | 410 | methodOop _method; |
duke@435 | 411 | public: |
duke@435 | 412 | compiledNode(methodOop method, TickPosition where) : ProfilerNode() { |
duke@435 | 413 | _method = method; |
duke@435 | 414 | update(where); |
duke@435 | 415 | } |
duke@435 | 416 | bool is_compiled() const { return true; } |
duke@435 | 417 | |
duke@435 | 418 | bool compiled_match(methodOop m) const { |
duke@435 | 419 | return _method == m; |
duke@435 | 420 | } |
duke@435 | 421 | |
duke@435 | 422 | methodOop method() { return _method; } |
duke@435 | 423 | |
duke@435 | 424 | void oops_do(OopClosure* f) { |
duke@435 | 425 | f->do_oop((oop*)&_method); |
duke@435 | 426 | } |
duke@435 | 427 | |
duke@435 | 428 | static void print_title(outputStream* st) { |
duke@435 | 429 | st->fill_to(col1); |
duke@435 | 430 | st->print("%11s", "Compiled"); |
duke@435 | 431 | ProfilerNode::print_title(st); |
duke@435 | 432 | } |
duke@435 | 433 | |
duke@435 | 434 | void print(outputStream* st, int total_ticks) { |
duke@435 | 435 | ProfilerNode::print(st, total_ticks); |
duke@435 | 436 | } |
duke@435 | 437 | |
duke@435 | 438 | void print_method_on(outputStream* st) { |
duke@435 | 439 | ProfilerNode::print_method_on(st); |
duke@435 | 440 | } |
duke@435 | 441 | }; |
duke@435 | 442 | |
duke@435 | 443 | class stubNode : public ProfilerNode { |
duke@435 | 444 | private: |
duke@435 | 445 | methodOop _method; |
duke@435 | 446 | const char* _symbol; // The name of the nearest VM symbol (for +ProfileVM). Points to a unique string |
duke@435 | 447 | public: |
duke@435 | 448 | stubNode(methodOop method, const char* name, TickPosition where) : ProfilerNode() { |
duke@435 | 449 | _method = method; |
duke@435 | 450 | _symbol = name; |
duke@435 | 451 | update(where); |
duke@435 | 452 | } |
duke@435 | 453 | |
duke@435 | 454 | bool is_stub() const { return true; } |
duke@435 | 455 | |
duke@435 | 456 | bool stub_match(methodOop m, const char* name) const { |
duke@435 | 457 | return (_method == m) && (_symbol == name); |
duke@435 | 458 | } |
duke@435 | 459 | |
duke@435 | 460 | void oops_do(OopClosure* f) { |
duke@435 | 461 | f->do_oop((oop*)&_method); |
duke@435 | 462 | } |
duke@435 | 463 | |
duke@435 | 464 | methodOop method() { return _method; } |
duke@435 | 465 | |
duke@435 | 466 | static void print_title(outputStream* st) { |
duke@435 | 467 | st->fill_to(col1); |
duke@435 | 468 | st->print("%11s", "Stub"); |
duke@435 | 469 | ProfilerNode::print_title(st); |
duke@435 | 470 | } |
duke@435 | 471 | |
duke@435 | 472 | void print(outputStream* st, int total_ticks) { |
duke@435 | 473 | ProfilerNode::print(st, total_ticks); |
duke@435 | 474 | } |
duke@435 | 475 | |
duke@435 | 476 | void print_method_on(outputStream* st) { |
duke@435 | 477 | ProfilerNode::print_method_on(st); |
duke@435 | 478 | print_symbol_on(st); |
duke@435 | 479 | } |
duke@435 | 480 | |
duke@435 | 481 | void print_symbol_on(outputStream* st) { |
duke@435 | 482 | if(_symbol) { |
duke@435 | 483 | st->print(" (%s)", _symbol); |
duke@435 | 484 | } |
duke@435 | 485 | } |
duke@435 | 486 | }; |
duke@435 | 487 | |
duke@435 | 488 | class adapterNode : public ProfilerNode { |
duke@435 | 489 | public: |
duke@435 | 490 | adapterNode(TickPosition where) : ProfilerNode() { |
duke@435 | 491 | update(where); |
duke@435 | 492 | } |
duke@435 | 493 | bool is_compiled() const { return true; } |
duke@435 | 494 | |
duke@435 | 495 | bool adapter_match() const { return true; } |
duke@435 | 496 | |
duke@435 | 497 | methodOop method() { return NULL; } |
duke@435 | 498 | |
duke@435 | 499 | void oops_do(OopClosure* f) { |
duke@435 | 500 | ; |
duke@435 | 501 | } |
duke@435 | 502 | |
duke@435 | 503 | void print(outputStream* st, int total_ticks) { |
duke@435 | 504 | ProfilerNode::print(st, total_ticks); |
duke@435 | 505 | } |
duke@435 | 506 | |
duke@435 | 507 | void print_method_on(outputStream* st) { |
duke@435 | 508 | st->print("%s", "adapters"); |
duke@435 | 509 | } |
duke@435 | 510 | }; |
duke@435 | 511 | |
duke@435 | 512 | class runtimeStubNode : public ProfilerNode { |
duke@435 | 513 | private: |
duke@435 | 514 | const CodeBlob* _stub; |
duke@435 | 515 | const char* _symbol; // The name of the nearest VM symbol when ProfileVM is on. Points to a unique string. |
duke@435 | 516 | public: |
duke@435 | 517 | runtimeStubNode(const CodeBlob* stub, const char* name, TickPosition where) : ProfilerNode(), _stub(stub), _symbol(name) { |
duke@435 | 518 | assert(stub->is_runtime_stub(), "wrong code blob"); |
duke@435 | 519 | update(where); |
duke@435 | 520 | } |
duke@435 | 521 | |
duke@435 | 522 | bool is_runtime_stub() const { return true; } |
duke@435 | 523 | |
duke@435 | 524 | bool runtimeStub_match(const CodeBlob* stub, const char* name) const { |
duke@435 | 525 | assert(stub->is_runtime_stub(), "wrong code blob"); |
duke@435 | 526 | return ((RuntimeStub*)_stub)->entry_point() == ((RuntimeStub*)stub)->entry_point() && |
duke@435 | 527 | (_symbol == name); |
duke@435 | 528 | } |
duke@435 | 529 | |
duke@435 | 530 | methodOop method() { return NULL; } |
duke@435 | 531 | |
duke@435 | 532 | static void print_title(outputStream* st) { |
duke@435 | 533 | st->fill_to(col1); |
duke@435 | 534 | st->print("%11s", "Runtime stub"); |
duke@435 | 535 | ProfilerNode::print_title(st); |
duke@435 | 536 | } |
duke@435 | 537 | |
duke@435 | 538 | void oops_do(OopClosure* f) { |
duke@435 | 539 | ; |
duke@435 | 540 | } |
duke@435 | 541 | |
duke@435 | 542 | void print(outputStream* st, int total_ticks) { |
duke@435 | 543 | ProfilerNode::print(st, total_ticks); |
duke@435 | 544 | } |
duke@435 | 545 | |
duke@435 | 546 | void print_method_on(outputStream* st) { |
duke@435 | 547 | st->print("%s", ((RuntimeStub*)_stub)->name()); |
duke@435 | 548 | print_symbol_on(st); |
duke@435 | 549 | } |
duke@435 | 550 | |
duke@435 | 551 | void print_symbol_on(outputStream* st) { |
duke@435 | 552 | if(_symbol) { |
duke@435 | 553 | st->print(" (%s)", _symbol); |
duke@435 | 554 | } |
duke@435 | 555 | } |
duke@435 | 556 | }; |
duke@435 | 557 | |
duke@435 | 558 | |
duke@435 | 559 | class unknown_compiledNode : public ProfilerNode { |
duke@435 | 560 | const char *_name; |
duke@435 | 561 | public: |
duke@435 | 562 | unknown_compiledNode(const CodeBlob* cb, TickPosition where) : ProfilerNode() { |
duke@435 | 563 | if ( cb->is_buffer_blob() ) |
duke@435 | 564 | _name = ((BufferBlob*)cb)->name(); |
duke@435 | 565 | else |
duke@435 | 566 | _name = ((SingletonBlob*)cb)->name(); |
duke@435 | 567 | update(where); |
duke@435 | 568 | } |
duke@435 | 569 | bool is_compiled() const { return true; } |
duke@435 | 570 | |
duke@435 | 571 | bool unknown_compiled_match(const CodeBlob* cb) const { |
duke@435 | 572 | if ( cb->is_buffer_blob() ) |
duke@435 | 573 | return !strcmp(((BufferBlob*)cb)->name(), _name); |
duke@435 | 574 | else |
duke@435 | 575 | return !strcmp(((SingletonBlob*)cb)->name(), _name); |
duke@435 | 576 | } |
duke@435 | 577 | |
duke@435 | 578 | methodOop method() { return NULL; } |
duke@435 | 579 | |
duke@435 | 580 | void oops_do(OopClosure* f) { |
duke@435 | 581 | ; |
duke@435 | 582 | } |
duke@435 | 583 | |
duke@435 | 584 | void print(outputStream* st, int total_ticks) { |
duke@435 | 585 | ProfilerNode::print(st, total_ticks); |
duke@435 | 586 | } |
duke@435 | 587 | |
duke@435 | 588 | void print_method_on(outputStream* st) { |
duke@435 | 589 | st->print("%s", _name); |
duke@435 | 590 | } |
duke@435 | 591 | }; |
duke@435 | 592 | |
duke@435 | 593 | class vmNode : public ProfilerNode { |
duke@435 | 594 | private: |
duke@435 | 595 | const char* _name; // "optional" name obtained by os means such as dll lookup |
duke@435 | 596 | public: |
duke@435 | 597 | vmNode(const TickPosition where) : ProfilerNode() { |
duke@435 | 598 | _name = NULL; |
duke@435 | 599 | update(where); |
duke@435 | 600 | } |
duke@435 | 601 | |
duke@435 | 602 | vmNode(const char* name, const TickPosition where) : ProfilerNode() { |
duke@435 | 603 | _name = name; |
duke@435 | 604 | update(where); |
duke@435 | 605 | } |
duke@435 | 606 | |
duke@435 | 607 | const char *name() const { return _name; } |
duke@435 | 608 | bool is_compiled() const { return true; } |
duke@435 | 609 | |
duke@435 | 610 | bool vm_match(const char* name) const { return strcmp(name, _name) == 0; } |
duke@435 | 611 | |
duke@435 | 612 | methodOop method() { return NULL; } |
duke@435 | 613 | |
duke@435 | 614 | static int hash(const char* name){ |
duke@435 | 615 | // Compute a simple hash |
duke@435 | 616 | const char* cp = name; |
duke@435 | 617 | int h = 0; |
duke@435 | 618 | |
duke@435 | 619 | if(name != NULL){ |
duke@435 | 620 | while(*cp != '\0'){ |
duke@435 | 621 | h = (h << 1) ^ *cp; |
duke@435 | 622 | cp++; |
duke@435 | 623 | } |
duke@435 | 624 | } |
duke@435 | 625 | return h; |
duke@435 | 626 | } |
duke@435 | 627 | |
duke@435 | 628 | void oops_do(OopClosure* f) { |
duke@435 | 629 | ; |
duke@435 | 630 | } |
duke@435 | 631 | |
duke@435 | 632 | void print(outputStream* st, int total_ticks) { |
duke@435 | 633 | ProfilerNode::print(st, total_ticks); |
duke@435 | 634 | } |
duke@435 | 635 | |
duke@435 | 636 | void print_method_on(outputStream* st) { |
duke@435 | 637 | if(_name==NULL){ |
duke@435 | 638 | st->print("%s", "unknown code"); |
duke@435 | 639 | } |
duke@435 | 640 | else { |
duke@435 | 641 | st->print("%s", _name); |
duke@435 | 642 | } |
duke@435 | 643 | } |
duke@435 | 644 | }; |
duke@435 | 645 | |
duke@435 | 646 | void ThreadProfiler::interpreted_update(methodOop method, TickPosition where) { |
duke@435 | 647 | int index = entry(ProfilerNode::hash(method)); |
duke@435 | 648 | if (!table[index]) { |
duke@435 | 649 | table[index] = new (this) interpretedNode(method, where); |
duke@435 | 650 | } else { |
duke@435 | 651 | ProfilerNode* prev = table[index]; |
duke@435 | 652 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 653 | if (node->interpreted_match(method)) { |
duke@435 | 654 | node->update(where); |
duke@435 | 655 | return; |
duke@435 | 656 | } |
duke@435 | 657 | prev = node; |
duke@435 | 658 | } |
duke@435 | 659 | prev->set_next(new (this) interpretedNode(method, where)); |
duke@435 | 660 | } |
duke@435 | 661 | } |
duke@435 | 662 | |
duke@435 | 663 | void ThreadProfiler::compiled_update(methodOop method, TickPosition where) { |
duke@435 | 664 | int index = entry(ProfilerNode::hash(method)); |
duke@435 | 665 | if (!table[index]) { |
duke@435 | 666 | table[index] = new (this) compiledNode(method, where); |
duke@435 | 667 | } else { |
duke@435 | 668 | ProfilerNode* prev = table[index]; |
duke@435 | 669 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 670 | if (node->compiled_match(method)) { |
duke@435 | 671 | node->update(where); |
duke@435 | 672 | return; |
duke@435 | 673 | } |
duke@435 | 674 | prev = node; |
duke@435 | 675 | } |
duke@435 | 676 | prev->set_next(new (this) compiledNode(method, where)); |
duke@435 | 677 | } |
duke@435 | 678 | } |
duke@435 | 679 | |
duke@435 | 680 | void ThreadProfiler::stub_update(methodOop method, const char* name, TickPosition where) { |
duke@435 | 681 | int index = entry(ProfilerNode::hash(method)); |
duke@435 | 682 | if (!table[index]) { |
duke@435 | 683 | table[index] = new (this) stubNode(method, name, where); |
duke@435 | 684 | } else { |
duke@435 | 685 | ProfilerNode* prev = table[index]; |
duke@435 | 686 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 687 | if (node->stub_match(method, name)) { |
duke@435 | 688 | node->update(where); |
duke@435 | 689 | return; |
duke@435 | 690 | } |
duke@435 | 691 | prev = node; |
duke@435 | 692 | } |
duke@435 | 693 | prev->set_next(new (this) stubNode(method, name, where)); |
duke@435 | 694 | } |
duke@435 | 695 | } |
duke@435 | 696 | |
duke@435 | 697 | void ThreadProfiler::adapter_update(TickPosition where) { |
duke@435 | 698 | int index = 0; |
duke@435 | 699 | if (!table[index]) { |
duke@435 | 700 | table[index] = new (this) adapterNode(where); |
duke@435 | 701 | } else { |
duke@435 | 702 | ProfilerNode* prev = table[index]; |
duke@435 | 703 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 704 | if (node->adapter_match()) { |
duke@435 | 705 | node->update(where); |
duke@435 | 706 | return; |
duke@435 | 707 | } |
duke@435 | 708 | prev = node; |
duke@435 | 709 | } |
duke@435 | 710 | prev->set_next(new (this) adapterNode(where)); |
duke@435 | 711 | } |
duke@435 | 712 | } |
duke@435 | 713 | |
duke@435 | 714 | void ThreadProfiler::runtime_stub_update(const CodeBlob* stub, const char* name, TickPosition where) { |
duke@435 | 715 | int index = 0; |
duke@435 | 716 | if (!table[index]) { |
duke@435 | 717 | table[index] = new (this) runtimeStubNode(stub, name, where); |
duke@435 | 718 | } else { |
duke@435 | 719 | ProfilerNode* prev = table[index]; |
duke@435 | 720 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 721 | if (node->runtimeStub_match(stub, name)) { |
duke@435 | 722 | node->update(where); |
duke@435 | 723 | return; |
duke@435 | 724 | } |
duke@435 | 725 | prev = node; |
duke@435 | 726 | } |
duke@435 | 727 | prev->set_next(new (this) runtimeStubNode(stub, name, where)); |
duke@435 | 728 | } |
duke@435 | 729 | } |
duke@435 | 730 | |
duke@435 | 731 | |
duke@435 | 732 | void ThreadProfiler::unknown_compiled_update(const CodeBlob* cb, TickPosition where) { |
duke@435 | 733 | int index = 0; |
duke@435 | 734 | if (!table[index]) { |
duke@435 | 735 | table[index] = new (this) unknown_compiledNode(cb, where); |
duke@435 | 736 | } else { |
duke@435 | 737 | ProfilerNode* prev = table[index]; |
duke@435 | 738 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 739 | if (node->unknown_compiled_match(cb)) { |
duke@435 | 740 | node->update(where); |
duke@435 | 741 | return; |
duke@435 | 742 | } |
duke@435 | 743 | prev = node; |
duke@435 | 744 | } |
duke@435 | 745 | prev->set_next(new (this) unknown_compiledNode(cb, where)); |
duke@435 | 746 | } |
duke@435 | 747 | } |
duke@435 | 748 | |
duke@435 | 749 | void ThreadProfiler::vm_update(TickPosition where) { |
duke@435 | 750 | vm_update(NULL, where); |
duke@435 | 751 | } |
duke@435 | 752 | |
duke@435 | 753 | void ThreadProfiler::vm_update(const char* name, TickPosition where) { |
duke@435 | 754 | int index = entry(vmNode::hash(name)); |
duke@435 | 755 | assert(index >= 0, "Must be positive"); |
duke@435 | 756 | // Note that we call strdup below since the symbol may be resource allocated |
duke@435 | 757 | if (!table[index]) { |
duke@435 | 758 | table[index] = new (this) vmNode(os::strdup(name), where); |
duke@435 | 759 | } else { |
duke@435 | 760 | ProfilerNode* prev = table[index]; |
duke@435 | 761 | for(ProfilerNode* node = prev; node; node = node->next()) { |
duke@435 | 762 | if (((vmNode *)node)->vm_match(name)) { |
duke@435 | 763 | node->update(where); |
duke@435 | 764 | return; |
duke@435 | 765 | } |
duke@435 | 766 | prev = node; |
duke@435 | 767 | } |
duke@435 | 768 | prev->set_next(new (this) vmNode(os::strdup(name), where)); |
duke@435 | 769 | } |
duke@435 | 770 | } |
duke@435 | 771 | |
duke@435 | 772 | |
duke@435 | 773 | class FlatProfilerTask : public PeriodicTask { |
duke@435 | 774 | public: |
duke@435 | 775 | FlatProfilerTask(int interval_time) : PeriodicTask(interval_time) {} |
duke@435 | 776 | void task(); |
duke@435 | 777 | }; |
duke@435 | 778 | |
duke@435 | 779 | void FlatProfiler::record_vm_operation() { |
duke@435 | 780 | if (Universe::heap()->is_gc_active()) { |
duke@435 | 781 | FlatProfiler::received_gc_ticks += 1; |
duke@435 | 782 | return; |
duke@435 | 783 | } |
duke@435 | 784 | |
duke@435 | 785 | if (DeoptimizationMarker::is_active()) { |
duke@435 | 786 | FlatProfiler::deopt_ticks += 1; |
duke@435 | 787 | return; |
duke@435 | 788 | } |
duke@435 | 789 | |
duke@435 | 790 | FlatProfiler::vm_operation_ticks += 1; |
duke@435 | 791 | } |
duke@435 | 792 | |
duke@435 | 793 | void FlatProfiler::record_vm_tick() { |
duke@435 | 794 | // Profile the VM Thread itself if needed |
duke@435 | 795 | // This is done without getting the Threads_lock and we can go deep |
duke@435 | 796 | // inside Safepoint, etc. |
duke@435 | 797 | if( ProfileVM ) { |
duke@435 | 798 | ResourceMark rm; |
duke@435 | 799 | ExtendedPC epc; |
duke@435 | 800 | const char *name = NULL; |
duke@435 | 801 | char buf[256]; |
duke@435 | 802 | buf[0] = '\0'; |
duke@435 | 803 | |
duke@435 | 804 | vm_thread_profiler->inc_thread_ticks(); |
duke@435 | 805 | |
duke@435 | 806 | // Get a snapshot of a current VMThread pc (and leave it running!) |
duke@435 | 807 | // The call may fail if, for instance the VM thread is interrupted while |
duke@435 | 808 | // holding the Interrupt_lock or for other reasons. |
duke@435 | 809 | epc = os::get_thread_pc(VMThread::vm_thread()); |
duke@435 | 810 | if(epc.pc() != NULL) { |
duke@435 | 811 | if (os::dll_address_to_function_name(epc.pc(), buf, sizeof(buf), NULL)) { |
duke@435 | 812 | name = buf; |
duke@435 | 813 | } |
duke@435 | 814 | } |
duke@435 | 815 | if (name != NULL) { |
duke@435 | 816 | vm_thread_profiler->vm_update(name, tp_native); |
duke@435 | 817 | } |
duke@435 | 818 | } |
duke@435 | 819 | } |
duke@435 | 820 | |
duke@435 | 821 | void FlatProfiler::record_thread_ticks() { |
duke@435 | 822 | |
duke@435 | 823 | int maxthreads, suspendedthreadcount; |
duke@435 | 824 | JavaThread** threadsList; |
duke@435 | 825 | bool interval_expired = false; |
duke@435 | 826 | |
duke@435 | 827 | if (ProfileIntervals && |
duke@435 | 828 | (FlatProfiler::received_ticks >= interval_ticks_previous + ProfileIntervalsTicks)) { |
duke@435 | 829 | interval_expired = true; |
duke@435 | 830 | interval_ticks_previous = FlatProfiler::received_ticks; |
duke@435 | 831 | } |
duke@435 | 832 | |
duke@435 | 833 | // Try not to wait for the Threads_lock |
duke@435 | 834 | if (Threads_lock->try_lock()) { |
duke@435 | 835 | { // Threads_lock scope |
duke@435 | 836 | maxthreads = Threads::number_of_threads(); |
duke@435 | 837 | threadsList = NEW_C_HEAP_ARRAY(JavaThread *, maxthreads); |
duke@435 | 838 | suspendedthreadcount = 0; |
duke@435 | 839 | for (JavaThread* tp = Threads::first(); tp != NULL; tp = tp->next()) { |
duke@435 | 840 | if (tp->is_Compiler_thread()) { |
duke@435 | 841 | // Only record ticks for active compiler threads |
duke@435 | 842 | CompilerThread* cthread = (CompilerThread*)tp; |
duke@435 | 843 | if (cthread->task() != NULL) { |
duke@435 | 844 | // The compiler is active. If we need to access any of the fields |
duke@435 | 845 | // of the compiler task we should suspend the CompilerThread first. |
duke@435 | 846 | FlatProfiler::compiler_ticks += 1; |
duke@435 | 847 | continue; |
duke@435 | 848 | } |
duke@435 | 849 | } |
duke@435 | 850 | |
duke@435 | 851 | // First externally suspend all threads by marking each for |
duke@435 | 852 | // external suspension - so it will stop at its next transition |
duke@435 | 853 | // Then do a safepoint |
duke@435 | 854 | ThreadProfiler* pp = tp->get_thread_profiler(); |
duke@435 | 855 | if (pp != NULL && pp->engaged) { |
duke@435 | 856 | MutexLockerEx ml(tp->SR_lock(), Mutex::_no_safepoint_check_flag); |
duke@435 | 857 | if (!tp->is_external_suspend() && !tp->is_exiting()) { |
duke@435 | 858 | tp->set_external_suspend(); |
duke@435 | 859 | threadsList[suspendedthreadcount++] = tp; |
duke@435 | 860 | } |
duke@435 | 861 | } |
duke@435 | 862 | } |
duke@435 | 863 | Threads_lock->unlock(); |
duke@435 | 864 | } |
duke@435 | 865 | // Suspend each thread. This call should just return |
duke@435 | 866 | // for any threads that have already self-suspended |
duke@435 | 867 | // Net result should be one safepoint |
duke@435 | 868 | for (int j = 0; j < suspendedthreadcount; j++) { |
duke@435 | 869 | JavaThread *tp = threadsList[j]; |
duke@435 | 870 | if (tp) { |
duke@435 | 871 | tp->java_suspend(); |
duke@435 | 872 | } |
duke@435 | 873 | } |
duke@435 | 874 | |
duke@435 | 875 | // We are responsible for resuming any thread on this list |
duke@435 | 876 | for (int i = 0; i < suspendedthreadcount; i++) { |
duke@435 | 877 | JavaThread *tp = threadsList[i]; |
duke@435 | 878 | if (tp) { |
duke@435 | 879 | ThreadProfiler* pp = tp->get_thread_profiler(); |
duke@435 | 880 | if (pp != NULL && pp->engaged) { |
duke@435 | 881 | HandleMark hm; |
duke@435 | 882 | FlatProfiler::delivered_ticks += 1; |
duke@435 | 883 | if (interval_expired) { |
duke@435 | 884 | FlatProfiler::interval_record_thread(pp); |
duke@435 | 885 | } |
duke@435 | 886 | // This is the place where we check to see if a user thread is |
duke@435 | 887 | // blocked waiting for compilation. |
duke@435 | 888 | if (tp->blocked_on_compilation()) { |
duke@435 | 889 | pp->compiler_ticks += 1; |
duke@435 | 890 | pp->interval_data_ref()->inc_compiling(); |
duke@435 | 891 | } else { |
duke@435 | 892 | pp->record_tick(tp); |
duke@435 | 893 | } |
duke@435 | 894 | } |
duke@435 | 895 | MutexLocker ml(Threads_lock); |
duke@435 | 896 | tp->java_resume(); |
duke@435 | 897 | } |
duke@435 | 898 | } |
duke@435 | 899 | if (interval_expired) { |
duke@435 | 900 | FlatProfiler::interval_print(); |
duke@435 | 901 | FlatProfiler::interval_reset(); |
duke@435 | 902 | } |
duke@435 | 903 | } else { |
duke@435 | 904 | // Couldn't get the threads lock, just record that rather than blocking |
duke@435 | 905 | FlatProfiler::threads_lock_ticks += 1; |
duke@435 | 906 | } |
duke@435 | 907 | |
duke@435 | 908 | } |
duke@435 | 909 | |
duke@435 | 910 | void FlatProfilerTask::task() { |
duke@435 | 911 | FlatProfiler::received_ticks += 1; |
duke@435 | 912 | |
duke@435 | 913 | if (ProfileVM) { |
duke@435 | 914 | FlatProfiler::record_vm_tick(); |
duke@435 | 915 | } |
duke@435 | 916 | |
duke@435 | 917 | VM_Operation* op = VMThread::vm_operation(); |
duke@435 | 918 | if (op != NULL) { |
duke@435 | 919 | FlatProfiler::record_vm_operation(); |
duke@435 | 920 | if (SafepointSynchronize::is_at_safepoint()) { |
duke@435 | 921 | return; |
duke@435 | 922 | } |
duke@435 | 923 | } |
duke@435 | 924 | FlatProfiler::record_thread_ticks(); |
duke@435 | 925 | } |
duke@435 | 926 | |
sgoldman@542 | 927 | void ThreadProfiler::record_interpreted_tick(JavaThread* thread, frame fr, TickPosition where, int* ticks) { |
duke@435 | 928 | FlatProfiler::all_int_ticks++; |
duke@435 | 929 | if (!FlatProfiler::full_profile()) { |
duke@435 | 930 | return; |
duke@435 | 931 | } |
duke@435 | 932 | |
sgoldman@542 | 933 | if (!fr.is_interpreted_frame_valid(thread)) { |
duke@435 | 934 | // tick came at a bad time |
duke@435 | 935 | interpreter_ticks += 1; |
duke@435 | 936 | FlatProfiler::interpreter_ticks += 1; |
duke@435 | 937 | return; |
duke@435 | 938 | } |
duke@435 | 939 | |
sgoldman@542 | 940 | // The frame has been fully validated so we can trust the method and bci |
sgoldman@542 | 941 | |
sgoldman@542 | 942 | methodOop method = *fr.interpreter_frame_method_addr(); |
sgoldman@542 | 943 | |
duke@435 | 944 | interpreted_update(method, where); |
duke@435 | 945 | |
duke@435 | 946 | // update byte code table |
duke@435 | 947 | InterpreterCodelet* desc = Interpreter::codelet_containing(fr.pc()); |
duke@435 | 948 | if (desc != NULL && desc->bytecode() >= 0) { |
duke@435 | 949 | ticks[desc->bytecode()]++; |
duke@435 | 950 | } |
duke@435 | 951 | } |
duke@435 | 952 | |
duke@435 | 953 | void ThreadProfiler::record_compiled_tick(JavaThread* thread, frame fr, TickPosition where) { |
duke@435 | 954 | const char *name = NULL; |
duke@435 | 955 | TickPosition localwhere = where; |
duke@435 | 956 | |
duke@435 | 957 | FlatProfiler::all_comp_ticks++; |
duke@435 | 958 | if (!FlatProfiler::full_profile()) return; |
duke@435 | 959 | |
duke@435 | 960 | CodeBlob* cb = fr.cb(); |
duke@435 | 961 | |
duke@435 | 962 | // For runtime stubs, record as native rather than as compiled |
duke@435 | 963 | if (cb->is_runtime_stub()) { |
duke@435 | 964 | RegisterMap map(thread, false); |
duke@435 | 965 | fr = fr.sender(&map); |
duke@435 | 966 | cb = fr.cb(); |
duke@435 | 967 | localwhere = tp_native; |
duke@435 | 968 | } |
duke@435 | 969 | methodOop method = (cb->is_nmethod()) ? ((nmethod *)cb)->method() : |
duke@435 | 970 | (methodOop)NULL; |
duke@435 | 971 | |
duke@435 | 972 | if (method == NULL) { |
duke@435 | 973 | if (cb->is_runtime_stub()) |
duke@435 | 974 | runtime_stub_update(cb, name, localwhere); |
duke@435 | 975 | else |
duke@435 | 976 | unknown_compiled_update(cb, localwhere); |
duke@435 | 977 | } |
duke@435 | 978 | else { |
duke@435 | 979 | if (method->is_native()) { |
duke@435 | 980 | stub_update(method, name, localwhere); |
duke@435 | 981 | } else { |
duke@435 | 982 | compiled_update(method, localwhere); |
duke@435 | 983 | } |
duke@435 | 984 | } |
duke@435 | 985 | } |
duke@435 | 986 | |
duke@435 | 987 | extern "C" void find(int x); |
duke@435 | 988 | |
duke@435 | 989 | |
duke@435 | 990 | void ThreadProfiler::record_tick_for_running_frame(JavaThread* thread, frame fr) { |
twisti@1040 | 991 | // The tick happened in real code -> non VM code |
duke@435 | 992 | if (fr.is_interpreted_frame()) { |
duke@435 | 993 | interval_data_ref()->inc_interpreted(); |
sgoldman@542 | 994 | record_interpreted_tick(thread, fr, tp_code, FlatProfiler::bytecode_ticks); |
duke@435 | 995 | return; |
duke@435 | 996 | } |
duke@435 | 997 | |
duke@435 | 998 | if (CodeCache::contains(fr.pc())) { |
duke@435 | 999 | interval_data_ref()->inc_compiled(); |
duke@435 | 1000 | PCRecorder::record(fr.pc()); |
duke@435 | 1001 | record_compiled_tick(thread, fr, tp_code); |
duke@435 | 1002 | return; |
duke@435 | 1003 | } |
duke@435 | 1004 | |
duke@435 | 1005 | if (VtableStubs::stub_containing(fr.pc()) != NULL) { |
duke@435 | 1006 | unknown_ticks_array[ut_vtable_stubs] += 1; |
duke@435 | 1007 | return; |
duke@435 | 1008 | } |
duke@435 | 1009 | |
duke@435 | 1010 | frame caller = fr.profile_find_Java_sender_frame(thread); |
duke@435 | 1011 | |
duke@435 | 1012 | if (caller.sp() != NULL && caller.pc() != NULL) { |
duke@435 | 1013 | record_tick_for_calling_frame(thread, caller); |
duke@435 | 1014 | return; |
duke@435 | 1015 | } |
duke@435 | 1016 | |
duke@435 | 1017 | unknown_ticks_array[ut_running_frame] += 1; |
duke@435 | 1018 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1019 | } |
duke@435 | 1020 | |
duke@435 | 1021 | void ThreadProfiler::record_tick_for_calling_frame(JavaThread* thread, frame fr) { |
twisti@1040 | 1022 | // The tick happened in VM code |
duke@435 | 1023 | interval_data_ref()->inc_native(); |
duke@435 | 1024 | if (fr.is_interpreted_frame()) { |
sgoldman@542 | 1025 | record_interpreted_tick(thread, fr, tp_native, FlatProfiler::bytecode_ticks_stub); |
duke@435 | 1026 | return; |
duke@435 | 1027 | } |
duke@435 | 1028 | if (CodeCache::contains(fr.pc())) { |
duke@435 | 1029 | record_compiled_tick(thread, fr, tp_native); |
duke@435 | 1030 | return; |
duke@435 | 1031 | } |
duke@435 | 1032 | |
duke@435 | 1033 | frame caller = fr.profile_find_Java_sender_frame(thread); |
duke@435 | 1034 | |
duke@435 | 1035 | if (caller.sp() != NULL && caller.pc() != NULL) { |
duke@435 | 1036 | record_tick_for_calling_frame(thread, caller); |
duke@435 | 1037 | return; |
duke@435 | 1038 | } |
duke@435 | 1039 | |
duke@435 | 1040 | unknown_ticks_array[ut_calling_frame] += 1; |
duke@435 | 1041 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1042 | } |
duke@435 | 1043 | |
duke@435 | 1044 | void ThreadProfiler::record_tick(JavaThread* thread) { |
duke@435 | 1045 | FlatProfiler::all_ticks++; |
duke@435 | 1046 | thread_ticks += 1; |
duke@435 | 1047 | |
duke@435 | 1048 | // Here's another way to track global state changes. |
duke@435 | 1049 | // When the class loader starts it marks the ThreadProfiler to tell it it is in the class loader |
duke@435 | 1050 | // and we check that here. |
duke@435 | 1051 | // This is more direct, and more than one thread can be in the class loader at a time, |
duke@435 | 1052 | // but it does mean the class loader has to know about the profiler. |
duke@435 | 1053 | if (region_flag[ThreadProfilerMark::classLoaderRegion]) { |
duke@435 | 1054 | class_loader_ticks += 1; |
duke@435 | 1055 | FlatProfiler::class_loader_ticks += 1; |
duke@435 | 1056 | return; |
duke@435 | 1057 | } else if (region_flag[ThreadProfilerMark::extraRegion]) { |
duke@435 | 1058 | extra_ticks += 1; |
duke@435 | 1059 | FlatProfiler::extra_ticks += 1; |
duke@435 | 1060 | return; |
duke@435 | 1061 | } |
duke@435 | 1062 | // Note that the WatcherThread can now stop for safepoints |
duke@435 | 1063 | uint32_t debug_bits = 0; |
duke@435 | 1064 | if (!thread->wait_for_ext_suspend_completion(SuspendRetryCount, |
duke@435 | 1065 | SuspendRetryDelay, &debug_bits)) { |
duke@435 | 1066 | unknown_ticks_array[ut_unknown_thread_state] += 1; |
duke@435 | 1067 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1068 | return; |
duke@435 | 1069 | } |
duke@435 | 1070 | |
duke@435 | 1071 | frame fr; |
duke@435 | 1072 | |
duke@435 | 1073 | switch (thread->thread_state()) { |
duke@435 | 1074 | case _thread_in_native: |
duke@435 | 1075 | case _thread_in_native_trans: |
duke@435 | 1076 | case _thread_in_vm: |
duke@435 | 1077 | case _thread_in_vm_trans: |
duke@435 | 1078 | if (thread->profile_last_Java_frame(&fr)) { |
duke@435 | 1079 | if (fr.is_runtime_frame()) { |
duke@435 | 1080 | RegisterMap map(thread, false); |
duke@435 | 1081 | fr = fr.sender(&map); |
duke@435 | 1082 | } |
duke@435 | 1083 | record_tick_for_calling_frame(thread, fr); |
duke@435 | 1084 | } else { |
duke@435 | 1085 | unknown_ticks_array[ut_no_last_Java_frame] += 1; |
duke@435 | 1086 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1087 | } |
duke@435 | 1088 | break; |
duke@435 | 1089 | // handle_special_runtime_exit_condition self-suspends threads in Java |
duke@435 | 1090 | case _thread_in_Java: |
duke@435 | 1091 | case _thread_in_Java_trans: |
duke@435 | 1092 | if (thread->profile_last_Java_frame(&fr)) { |
duke@435 | 1093 | if (fr.is_safepoint_blob_frame()) { |
duke@435 | 1094 | RegisterMap map(thread, false); |
duke@435 | 1095 | fr = fr.sender(&map); |
duke@435 | 1096 | } |
duke@435 | 1097 | record_tick_for_running_frame(thread, fr); |
duke@435 | 1098 | } else { |
duke@435 | 1099 | unknown_ticks_array[ut_no_last_Java_frame] += 1; |
duke@435 | 1100 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1101 | } |
duke@435 | 1102 | break; |
duke@435 | 1103 | case _thread_blocked: |
duke@435 | 1104 | case _thread_blocked_trans: |
duke@435 | 1105 | if (thread->osthread() && thread->osthread()->get_state() == RUNNABLE) { |
duke@435 | 1106 | if (thread->profile_last_Java_frame(&fr)) { |
duke@435 | 1107 | if (fr.is_safepoint_blob_frame()) { |
duke@435 | 1108 | RegisterMap map(thread, false); |
duke@435 | 1109 | fr = fr.sender(&map); |
duke@435 | 1110 | record_tick_for_running_frame(thread, fr); |
duke@435 | 1111 | } else { |
duke@435 | 1112 | record_tick_for_calling_frame(thread, fr); |
duke@435 | 1113 | } |
duke@435 | 1114 | } else { |
duke@435 | 1115 | unknown_ticks_array[ut_no_last_Java_frame] += 1; |
duke@435 | 1116 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1117 | } |
duke@435 | 1118 | } else { |
duke@435 | 1119 | blocked_ticks += 1; |
duke@435 | 1120 | FlatProfiler::blocked_ticks += 1; |
duke@435 | 1121 | } |
duke@435 | 1122 | break; |
duke@435 | 1123 | case _thread_uninitialized: |
duke@435 | 1124 | case _thread_new: |
duke@435 | 1125 | // not used, included for completeness |
duke@435 | 1126 | case _thread_new_trans: |
duke@435 | 1127 | unknown_ticks_array[ut_no_last_Java_frame] += 1; |
duke@435 | 1128 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1129 | break; |
duke@435 | 1130 | default: |
duke@435 | 1131 | unknown_ticks_array[ut_unknown_thread_state] += 1; |
duke@435 | 1132 | FlatProfiler::unknown_ticks += 1; |
duke@435 | 1133 | break; |
duke@435 | 1134 | } |
duke@435 | 1135 | return; |
duke@435 | 1136 | } |
duke@435 | 1137 | |
duke@435 | 1138 | void ThreadProfiler::engage() { |
duke@435 | 1139 | engaged = true; |
duke@435 | 1140 | timer.start(); |
duke@435 | 1141 | } |
duke@435 | 1142 | |
duke@435 | 1143 | void ThreadProfiler::disengage() { |
duke@435 | 1144 | engaged = false; |
duke@435 | 1145 | timer.stop(); |
duke@435 | 1146 | } |
duke@435 | 1147 | |
duke@435 | 1148 | void ThreadProfiler::initialize() { |
duke@435 | 1149 | for (int index = 0; index < table_size; index++) { |
duke@435 | 1150 | table[index] = NULL; |
duke@435 | 1151 | } |
duke@435 | 1152 | thread_ticks = 0; |
duke@435 | 1153 | blocked_ticks = 0; |
duke@435 | 1154 | compiler_ticks = 0; |
duke@435 | 1155 | interpreter_ticks = 0; |
duke@435 | 1156 | for (int ut = 0; ut < ut_end; ut += 1) { |
duke@435 | 1157 | unknown_ticks_array[ut] = 0; |
duke@435 | 1158 | } |
duke@435 | 1159 | region_flag[ThreadProfilerMark::classLoaderRegion] = false; |
duke@435 | 1160 | class_loader_ticks = 0; |
duke@435 | 1161 | region_flag[ThreadProfilerMark::extraRegion] = false; |
duke@435 | 1162 | extra_ticks = 0; |
duke@435 | 1163 | timer.start(); |
duke@435 | 1164 | interval_data_ref()->reset(); |
duke@435 | 1165 | } |
duke@435 | 1166 | |
duke@435 | 1167 | void ThreadProfiler::reset() { |
duke@435 | 1168 | timer.stop(); |
duke@435 | 1169 | if (table != NULL) { |
duke@435 | 1170 | for (int index = 0; index < table_size; index++) { |
duke@435 | 1171 | ProfilerNode* n = table[index]; |
duke@435 | 1172 | if (n != NULL) { |
duke@435 | 1173 | delete n; |
duke@435 | 1174 | } |
duke@435 | 1175 | } |
duke@435 | 1176 | } |
duke@435 | 1177 | initialize(); |
duke@435 | 1178 | } |
duke@435 | 1179 | |
duke@435 | 1180 | void FlatProfiler::allocate_table() { |
duke@435 | 1181 | { // Bytecode table |
duke@435 | 1182 | bytecode_ticks = NEW_C_HEAP_ARRAY(int, Bytecodes::number_of_codes); |
duke@435 | 1183 | bytecode_ticks_stub = NEW_C_HEAP_ARRAY(int, Bytecodes::number_of_codes); |
duke@435 | 1184 | for(int index = 0; index < Bytecodes::number_of_codes; index++) { |
duke@435 | 1185 | bytecode_ticks[index] = 0; |
duke@435 | 1186 | bytecode_ticks_stub[index] = 0; |
duke@435 | 1187 | } |
duke@435 | 1188 | } |
duke@435 | 1189 | |
duke@435 | 1190 | if (ProfilerRecordPC) PCRecorder::init(); |
duke@435 | 1191 | |
duke@435 | 1192 | interval_data = NEW_C_HEAP_ARRAY(IntervalData, interval_print_size); |
duke@435 | 1193 | FlatProfiler::interval_reset(); |
duke@435 | 1194 | } |
duke@435 | 1195 | |
duke@435 | 1196 | void FlatProfiler::engage(JavaThread* mainThread, bool fullProfile) { |
duke@435 | 1197 | full_profile_flag = fullProfile; |
duke@435 | 1198 | if (bytecode_ticks == NULL) { |
duke@435 | 1199 | allocate_table(); |
duke@435 | 1200 | } |
duke@435 | 1201 | if(ProfileVM && (vm_thread_profiler == NULL)){ |
duke@435 | 1202 | vm_thread_profiler = new ThreadProfiler(); |
duke@435 | 1203 | } |
duke@435 | 1204 | if (task == NULL) { |
duke@435 | 1205 | task = new FlatProfilerTask(WatcherThread::delay_interval); |
duke@435 | 1206 | task->enroll(); |
duke@435 | 1207 | } |
duke@435 | 1208 | timer.start(); |
duke@435 | 1209 | if (mainThread != NULL) { |
duke@435 | 1210 | // When mainThread was created, it might not have a ThreadProfiler |
duke@435 | 1211 | ThreadProfiler* pp = mainThread->get_thread_profiler(); |
duke@435 | 1212 | if (pp == NULL) { |
duke@435 | 1213 | mainThread->set_thread_profiler(new ThreadProfiler()); |
duke@435 | 1214 | } else { |
duke@435 | 1215 | pp->reset(); |
duke@435 | 1216 | } |
duke@435 | 1217 | mainThread->get_thread_profiler()->engage(); |
duke@435 | 1218 | } |
duke@435 | 1219 | // This is where we would assign thread_profiler |
duke@435 | 1220 | // if we wanted only one thread_profiler for all threads. |
duke@435 | 1221 | thread_profiler = NULL; |
duke@435 | 1222 | } |
duke@435 | 1223 | |
duke@435 | 1224 | void FlatProfiler::disengage() { |
duke@435 | 1225 | if (!task) { |
duke@435 | 1226 | return; |
duke@435 | 1227 | } |
duke@435 | 1228 | timer.stop(); |
duke@435 | 1229 | task->disenroll(); |
duke@435 | 1230 | delete task; |
duke@435 | 1231 | task = NULL; |
duke@435 | 1232 | if (thread_profiler != NULL) { |
duke@435 | 1233 | thread_profiler->disengage(); |
duke@435 | 1234 | } else { |
duke@435 | 1235 | MutexLocker tl(Threads_lock); |
duke@435 | 1236 | for (JavaThread* tp = Threads::first(); tp != NULL; tp = tp->next()) { |
duke@435 | 1237 | ThreadProfiler* pp = tp->get_thread_profiler(); |
duke@435 | 1238 | if (pp != NULL) { |
duke@435 | 1239 | pp->disengage(); |
duke@435 | 1240 | } |
duke@435 | 1241 | } |
duke@435 | 1242 | } |
duke@435 | 1243 | } |
duke@435 | 1244 | |
duke@435 | 1245 | void FlatProfiler::reset() { |
duke@435 | 1246 | if (task) { |
duke@435 | 1247 | disengage(); |
duke@435 | 1248 | } |
duke@435 | 1249 | |
duke@435 | 1250 | class_loader_ticks = 0; |
duke@435 | 1251 | extra_ticks = 0; |
duke@435 | 1252 | received_gc_ticks = 0; |
duke@435 | 1253 | vm_operation_ticks = 0; |
duke@435 | 1254 | compiler_ticks = 0; |
duke@435 | 1255 | deopt_ticks = 0; |
duke@435 | 1256 | interpreter_ticks = 0; |
duke@435 | 1257 | blocked_ticks = 0; |
duke@435 | 1258 | unknown_ticks = 0; |
duke@435 | 1259 | received_ticks = 0; |
duke@435 | 1260 | delivered_ticks = 0; |
duke@435 | 1261 | timer.stop(); |
duke@435 | 1262 | } |
duke@435 | 1263 | |
duke@435 | 1264 | bool FlatProfiler::is_active() { |
duke@435 | 1265 | return task != NULL; |
duke@435 | 1266 | } |
duke@435 | 1267 | |
duke@435 | 1268 | void FlatProfiler::print_byte_code_statistics() { |
duke@435 | 1269 | GrowableArray <ProfilerNode*>* array = new GrowableArray<ProfilerNode*>(200); |
duke@435 | 1270 | |
duke@435 | 1271 | tty->print_cr(" Bytecode ticks:"); |
duke@435 | 1272 | for (int index = 0; index < Bytecodes::number_of_codes; index++) { |
duke@435 | 1273 | if (FlatProfiler::bytecode_ticks[index] > 0 || FlatProfiler::bytecode_ticks_stub[index] > 0) { |
duke@435 | 1274 | tty->print_cr(" %4d %4d = %s", |
duke@435 | 1275 | FlatProfiler::bytecode_ticks[index], |
duke@435 | 1276 | FlatProfiler::bytecode_ticks_stub[index], |
duke@435 | 1277 | Bytecodes::name( (Bytecodes::Code) index)); |
duke@435 | 1278 | } |
duke@435 | 1279 | } |
duke@435 | 1280 | tty->cr(); |
duke@435 | 1281 | } |
duke@435 | 1282 | |
duke@435 | 1283 | void print_ticks(const char* title, int ticks, int total) { |
duke@435 | 1284 | if (ticks > 0) { |
duke@435 | 1285 | tty->print("%5.1f%% %5d", ticks * 100.0 / total, ticks); |
duke@435 | 1286 | tty->fill_to(col3); |
duke@435 | 1287 | tty->print("%s", title); |
duke@435 | 1288 | tty->cr(); |
duke@435 | 1289 | } |
duke@435 | 1290 | } |
duke@435 | 1291 | |
duke@435 | 1292 | void ThreadProfiler::print(const char* thread_name) { |
duke@435 | 1293 | ResourceMark rm; |
duke@435 | 1294 | MutexLocker ppl(ProfilePrint_lock); |
duke@435 | 1295 | int index = 0; // Declared outside for loops for portability |
duke@435 | 1296 | |
duke@435 | 1297 | if (table == NULL) { |
duke@435 | 1298 | return; |
duke@435 | 1299 | } |
duke@435 | 1300 | |
duke@435 | 1301 | if (thread_ticks <= 0) { |
duke@435 | 1302 | return; |
duke@435 | 1303 | } |
duke@435 | 1304 | |
duke@435 | 1305 | const char* title = "too soon to tell"; |
duke@435 | 1306 | double secs = timer.seconds(); |
duke@435 | 1307 | |
duke@435 | 1308 | GrowableArray <ProfilerNode*>* array = new GrowableArray<ProfilerNode*>(200); |
duke@435 | 1309 | for(index = 0; index < table_size; index++) { |
duke@435 | 1310 | for(ProfilerNode* node = table[index]; node; node = node->next()) |
duke@435 | 1311 | array->append(node); |
duke@435 | 1312 | } |
duke@435 | 1313 | |
duke@435 | 1314 | array->sort(&ProfilerNode::compare); |
duke@435 | 1315 | |
duke@435 | 1316 | // compute total (sanity check) |
duke@435 | 1317 | int active = |
duke@435 | 1318 | class_loader_ticks + |
duke@435 | 1319 | compiler_ticks + |
duke@435 | 1320 | interpreter_ticks + |
duke@435 | 1321 | unknown_ticks(); |
duke@435 | 1322 | for (index = 0; index < array->length(); index++) { |
duke@435 | 1323 | active += array->at(index)->ticks.total(); |
duke@435 | 1324 | } |
duke@435 | 1325 | int total = active + blocked_ticks; |
duke@435 | 1326 | |
duke@435 | 1327 | tty->cr(); |
duke@435 | 1328 | tty->print_cr("Flat profile of %3.2f secs (%d total ticks): %s", secs, total, thread_name); |
duke@435 | 1329 | if (total != thread_ticks) { |
duke@435 | 1330 | print_ticks("Lost ticks", thread_ticks-total, thread_ticks); |
duke@435 | 1331 | } |
duke@435 | 1332 | tty->cr(); |
duke@435 | 1333 | |
duke@435 | 1334 | // print interpreted methods |
duke@435 | 1335 | tick_counter interpreted_ticks; |
duke@435 | 1336 | bool has_interpreted_ticks = false; |
duke@435 | 1337 | int print_count = 0; |
duke@435 | 1338 | for (index = 0; index < array->length(); index++) { |
duke@435 | 1339 | ProfilerNode* n = array->at(index); |
duke@435 | 1340 | if (n->is_interpreted()) { |
duke@435 | 1341 | interpreted_ticks.add(&n->ticks); |
duke@435 | 1342 | if (!has_interpreted_ticks) { |
duke@435 | 1343 | interpretedNode::print_title(tty); |
duke@435 | 1344 | has_interpreted_ticks = true; |
duke@435 | 1345 | } |
duke@435 | 1346 | if (print_count++ < ProfilerNumberOfInterpretedMethods) { |
duke@435 | 1347 | n->print(tty, active); |
duke@435 | 1348 | } |
duke@435 | 1349 | } |
duke@435 | 1350 | } |
duke@435 | 1351 | if (has_interpreted_ticks) { |
duke@435 | 1352 | if (print_count <= ProfilerNumberOfInterpretedMethods) { |
duke@435 | 1353 | title = "Total interpreted"; |
duke@435 | 1354 | } else { |
duke@435 | 1355 | title = "Total interpreted (including elided)"; |
duke@435 | 1356 | } |
duke@435 | 1357 | interpretedNode::print_total(tty, &interpreted_ticks, active, title); |
duke@435 | 1358 | tty->cr(); |
duke@435 | 1359 | } |
duke@435 | 1360 | |
duke@435 | 1361 | // print compiled methods |
duke@435 | 1362 | tick_counter compiled_ticks; |
duke@435 | 1363 | bool has_compiled_ticks = false; |
duke@435 | 1364 | print_count = 0; |
duke@435 | 1365 | for (index = 0; index < array->length(); index++) { |
duke@435 | 1366 | ProfilerNode* n = array->at(index); |
duke@435 | 1367 | if (n->is_compiled()) { |
duke@435 | 1368 | compiled_ticks.add(&n->ticks); |
duke@435 | 1369 | if (!has_compiled_ticks) { |
duke@435 | 1370 | compiledNode::print_title(tty); |
duke@435 | 1371 | has_compiled_ticks = true; |
duke@435 | 1372 | } |
duke@435 | 1373 | if (print_count++ < ProfilerNumberOfCompiledMethods) { |
duke@435 | 1374 | n->print(tty, active); |
duke@435 | 1375 | } |
duke@435 | 1376 | } |
duke@435 | 1377 | } |
duke@435 | 1378 | if (has_compiled_ticks) { |
duke@435 | 1379 | if (print_count <= ProfilerNumberOfCompiledMethods) { |
duke@435 | 1380 | title = "Total compiled"; |
duke@435 | 1381 | } else { |
duke@435 | 1382 | title = "Total compiled (including elided)"; |
duke@435 | 1383 | } |
duke@435 | 1384 | compiledNode::print_total(tty, &compiled_ticks, active, title); |
duke@435 | 1385 | tty->cr(); |
duke@435 | 1386 | } |
duke@435 | 1387 | |
duke@435 | 1388 | // print stub methods |
duke@435 | 1389 | tick_counter stub_ticks; |
duke@435 | 1390 | bool has_stub_ticks = false; |
duke@435 | 1391 | print_count = 0; |
duke@435 | 1392 | for (index = 0; index < array->length(); index++) { |
duke@435 | 1393 | ProfilerNode* n = array->at(index); |
duke@435 | 1394 | if (n->is_stub()) { |
duke@435 | 1395 | stub_ticks.add(&n->ticks); |
duke@435 | 1396 | if (!has_stub_ticks) { |
duke@435 | 1397 | stubNode::print_title(tty); |
duke@435 | 1398 | has_stub_ticks = true; |
duke@435 | 1399 | } |
duke@435 | 1400 | if (print_count++ < ProfilerNumberOfStubMethods) { |
duke@435 | 1401 | n->print(tty, active); |
duke@435 | 1402 | } |
duke@435 | 1403 | } |
duke@435 | 1404 | } |
duke@435 | 1405 | if (has_stub_ticks) { |
duke@435 | 1406 | if (print_count <= ProfilerNumberOfStubMethods) { |
duke@435 | 1407 | title = "Total stub"; |
duke@435 | 1408 | } else { |
duke@435 | 1409 | title = "Total stub (including elided)"; |
duke@435 | 1410 | } |
duke@435 | 1411 | stubNode::print_total(tty, &stub_ticks, active, title); |
duke@435 | 1412 | tty->cr(); |
duke@435 | 1413 | } |
duke@435 | 1414 | |
duke@435 | 1415 | // print runtime stubs |
duke@435 | 1416 | tick_counter runtime_stub_ticks; |
duke@435 | 1417 | bool has_runtime_stub_ticks = false; |
duke@435 | 1418 | print_count = 0; |
duke@435 | 1419 | for (index = 0; index < array->length(); index++) { |
duke@435 | 1420 | ProfilerNode* n = array->at(index); |
duke@435 | 1421 | if (n->is_runtime_stub()) { |
duke@435 | 1422 | runtime_stub_ticks.add(&n->ticks); |
duke@435 | 1423 | if (!has_runtime_stub_ticks) { |
duke@435 | 1424 | runtimeStubNode::print_title(tty); |
duke@435 | 1425 | has_runtime_stub_ticks = true; |
duke@435 | 1426 | } |
duke@435 | 1427 | if (print_count++ < ProfilerNumberOfRuntimeStubNodes) { |
duke@435 | 1428 | n->print(tty, active); |
duke@435 | 1429 | } |
duke@435 | 1430 | } |
duke@435 | 1431 | } |
duke@435 | 1432 | if (has_runtime_stub_ticks) { |
duke@435 | 1433 | if (print_count <= ProfilerNumberOfRuntimeStubNodes) { |
duke@435 | 1434 | title = "Total runtime stubs"; |
duke@435 | 1435 | } else { |
duke@435 | 1436 | title = "Total runtime stubs (including elided)"; |
duke@435 | 1437 | } |
duke@435 | 1438 | runtimeStubNode::print_total(tty, &runtime_stub_ticks, active, title); |
duke@435 | 1439 | tty->cr(); |
duke@435 | 1440 | } |
duke@435 | 1441 | |
duke@435 | 1442 | if (blocked_ticks + class_loader_ticks + interpreter_ticks + compiler_ticks + unknown_ticks() != 0) { |
duke@435 | 1443 | tty->fill_to(col1); |
duke@435 | 1444 | tty->print_cr("Thread-local ticks:"); |
duke@435 | 1445 | print_ticks("Blocked (of total)", blocked_ticks, total); |
duke@435 | 1446 | print_ticks("Class loader", class_loader_ticks, active); |
duke@435 | 1447 | print_ticks("Extra", extra_ticks, active); |
duke@435 | 1448 | print_ticks("Interpreter", interpreter_ticks, active); |
duke@435 | 1449 | print_ticks("Compilation", compiler_ticks, active); |
duke@435 | 1450 | print_ticks("Unknown: vtable stubs", unknown_ticks_array[ut_vtable_stubs], active); |
duke@435 | 1451 | print_ticks("Unknown: null method", unknown_ticks_array[ut_null_method], active); |
duke@435 | 1452 | print_ticks("Unknown: running frame", unknown_ticks_array[ut_running_frame], active); |
duke@435 | 1453 | print_ticks("Unknown: calling frame", unknown_ticks_array[ut_calling_frame], active); |
duke@435 | 1454 | print_ticks("Unknown: no pc", unknown_ticks_array[ut_no_pc], active); |
duke@435 | 1455 | print_ticks("Unknown: no last frame", unknown_ticks_array[ut_no_last_Java_frame], active); |
duke@435 | 1456 | print_ticks("Unknown: thread_state", unknown_ticks_array[ut_unknown_thread_state], active); |
duke@435 | 1457 | tty->cr(); |
duke@435 | 1458 | } |
duke@435 | 1459 | |
duke@435 | 1460 | if (WizardMode) { |
duke@435 | 1461 | tty->print_cr("Node area used: %dKb", (area_top - area_bottom) / 1024); |
duke@435 | 1462 | } |
duke@435 | 1463 | reset(); |
duke@435 | 1464 | } |
duke@435 | 1465 | |
duke@435 | 1466 | /* |
duke@435 | 1467 | ThreadProfiler::print_unknown(){ |
duke@435 | 1468 | if (table == NULL) { |
duke@435 | 1469 | return; |
duke@435 | 1470 | } |
duke@435 | 1471 | |
duke@435 | 1472 | if (thread_ticks <= 0) { |
duke@435 | 1473 | return; |
duke@435 | 1474 | } |
duke@435 | 1475 | } */ |
duke@435 | 1476 | |
duke@435 | 1477 | void FlatProfiler::print(int unused) { |
duke@435 | 1478 | ResourceMark rm; |
duke@435 | 1479 | if (thread_profiler != NULL) { |
duke@435 | 1480 | thread_profiler->print("All threads"); |
duke@435 | 1481 | } else { |
duke@435 | 1482 | MutexLocker tl(Threads_lock); |
duke@435 | 1483 | for (JavaThread* tp = Threads::first(); tp != NULL; tp = tp->next()) { |
duke@435 | 1484 | ThreadProfiler* pp = tp->get_thread_profiler(); |
duke@435 | 1485 | if (pp != NULL) { |
duke@435 | 1486 | pp->print(tp->get_thread_name()); |
duke@435 | 1487 | } |
duke@435 | 1488 | } |
duke@435 | 1489 | } |
duke@435 | 1490 | |
duke@435 | 1491 | if (ProfilerPrintByteCodeStatistics) { |
duke@435 | 1492 | print_byte_code_statistics(); |
duke@435 | 1493 | } |
duke@435 | 1494 | |
duke@435 | 1495 | if (non_method_ticks() > 0) { |
duke@435 | 1496 | tty->cr(); |
duke@435 | 1497 | tty->print_cr("Global summary of %3.2f seconds:", timer.seconds()); |
duke@435 | 1498 | print_ticks("Received ticks", received_ticks, received_ticks); |
duke@435 | 1499 | print_ticks("Received GC ticks", received_gc_ticks, received_ticks); |
duke@435 | 1500 | print_ticks("Compilation", compiler_ticks, received_ticks); |
duke@435 | 1501 | print_ticks("Deoptimization", deopt_ticks, received_ticks); |
duke@435 | 1502 | print_ticks("Other VM operations", vm_operation_ticks, received_ticks); |
duke@435 | 1503 | #ifndef PRODUCT |
duke@435 | 1504 | print_ticks("Blocked ticks", blocked_ticks, received_ticks); |
duke@435 | 1505 | print_ticks("Threads_lock blocks", threads_lock_ticks, received_ticks); |
duke@435 | 1506 | print_ticks("Delivered ticks", delivered_ticks, received_ticks); |
duke@435 | 1507 | print_ticks("All ticks", all_ticks, received_ticks); |
duke@435 | 1508 | #endif |
duke@435 | 1509 | print_ticks("Class loader", class_loader_ticks, received_ticks); |
duke@435 | 1510 | print_ticks("Extra ", extra_ticks, received_ticks); |
duke@435 | 1511 | print_ticks("Interpreter", interpreter_ticks, received_ticks); |
duke@435 | 1512 | print_ticks("Unknown code", unknown_ticks, received_ticks); |
duke@435 | 1513 | } |
duke@435 | 1514 | |
duke@435 | 1515 | PCRecorder::print(); |
duke@435 | 1516 | |
duke@435 | 1517 | if(ProfileVM){ |
duke@435 | 1518 | tty->cr(); |
duke@435 | 1519 | vm_thread_profiler->print("VM Thread"); |
duke@435 | 1520 | } |
duke@435 | 1521 | } |
duke@435 | 1522 | |
duke@435 | 1523 | void IntervalData::print_header(outputStream* st) { |
duke@435 | 1524 | st->print("i/c/n/g"); |
duke@435 | 1525 | } |
duke@435 | 1526 | |
duke@435 | 1527 | void IntervalData::print_data(outputStream* st) { |
duke@435 | 1528 | st->print("%d/%d/%d/%d", interpreted(), compiled(), native(), compiling()); |
duke@435 | 1529 | } |
duke@435 | 1530 | |
duke@435 | 1531 | void FlatProfiler::interval_record_thread(ThreadProfiler* tp) { |
duke@435 | 1532 | IntervalData id = tp->interval_data(); |
duke@435 | 1533 | int total = id.total(); |
duke@435 | 1534 | tp->interval_data_ref()->reset(); |
duke@435 | 1535 | |
duke@435 | 1536 | // Insertion sort the data, if it's relevant. |
duke@435 | 1537 | for (int i = 0; i < interval_print_size; i += 1) { |
duke@435 | 1538 | if (total > interval_data[i].total()) { |
duke@435 | 1539 | for (int j = interval_print_size - 1; j > i; j -= 1) { |
duke@435 | 1540 | interval_data[j] = interval_data[j-1]; |
duke@435 | 1541 | } |
duke@435 | 1542 | interval_data[i] = id; |
duke@435 | 1543 | break; |
duke@435 | 1544 | } |
duke@435 | 1545 | } |
duke@435 | 1546 | } |
duke@435 | 1547 | |
duke@435 | 1548 | void FlatProfiler::interval_print() { |
duke@435 | 1549 | if ((interval_data[0].total() > 0)) { |
duke@435 | 1550 | tty->stamp(); |
duke@435 | 1551 | tty->print("\t"); |
duke@435 | 1552 | IntervalData::print_header(tty); |
duke@435 | 1553 | for (int i = 0; i < interval_print_size; i += 1) { |
duke@435 | 1554 | if (interval_data[i].total() > 0) { |
duke@435 | 1555 | tty->print("\t"); |
duke@435 | 1556 | interval_data[i].print_data(tty); |
duke@435 | 1557 | } |
duke@435 | 1558 | } |
duke@435 | 1559 | tty->cr(); |
duke@435 | 1560 | } |
duke@435 | 1561 | } |
duke@435 | 1562 | |
duke@435 | 1563 | void FlatProfiler::interval_reset() { |
duke@435 | 1564 | for (int i = 0; i < interval_print_size; i += 1) { |
duke@435 | 1565 | interval_data[i].reset(); |
duke@435 | 1566 | } |
duke@435 | 1567 | } |
duke@435 | 1568 | |
duke@435 | 1569 | void ThreadProfiler::oops_do(OopClosure* f) { |
duke@435 | 1570 | if (table == NULL) return; |
duke@435 | 1571 | |
duke@435 | 1572 | for(int index = 0; index < table_size; index++) { |
duke@435 | 1573 | for(ProfilerNode* node = table[index]; node; node = node->next()) |
duke@435 | 1574 | node->oops_do(f); |
duke@435 | 1575 | } |
duke@435 | 1576 | } |
duke@435 | 1577 | |
duke@435 | 1578 | void FlatProfiler::oops_do(OopClosure* f) { |
duke@435 | 1579 | if (thread_profiler != NULL) { |
duke@435 | 1580 | thread_profiler->oops_do(f); |
duke@435 | 1581 | } else { |
duke@435 | 1582 | for (JavaThread* tp = Threads::first(); tp != NULL; tp = tp->next()) { |
duke@435 | 1583 | ThreadProfiler* pp = tp->get_thread_profiler(); |
duke@435 | 1584 | if (pp != NULL) { |
duke@435 | 1585 | pp->oops_do(f); |
duke@435 | 1586 | } |
duke@435 | 1587 | } |
duke@435 | 1588 | } |
duke@435 | 1589 | } |