src/hotspot/share/jfr/periodic/jfrThreadCPULoadEvent.cpp
author mgronlun
Wed, 30 Oct 2019 19:43:52 +0100
changeset 58863 c16ac7a2eba4
parent 50113 caf115bb98ad
child 57878 bffba8d6611a
permissions -rw-r--r--
8226511: Implement JFR Event Streaming Reviewed-by: egahlin, mseledtsov, mgronlun Contributed-by: erik.gahlin@oracle.com, mikhailo.seledtsov@oracle.com, markus.gronlund@oracle.com
Ignore whitespace changes - Everywhere: Within whitespace: At end of lines:
50113
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     1
/*
58863
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
     2
 * Copyright (c) 2017, 2019, Oracle and/or its affiliates. All rights reserved.
50113
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     3
 * DO NOT ALTER OR REMOVE COPYRIGHT NOTICES OR THIS FILE HEADER.
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     4
 *
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     5
 * This code is free software; you can redistribute it and/or modify it
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     6
 * under the terms of the GNU General Public License version 2 only, as
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     7
 * published by the Free Software Foundation.
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     8
 *
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
     9
 * This code is distributed in the hope that it will be useful, but WITHOUT
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    10
 * ANY WARRANTY; without even the implied warranty of MERCHANTABILITY or
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    11
 * FITNESS FOR A PARTICULAR PURPOSE.  See the GNU General Public License
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    12
 * version 2 for more details (a copy is included in the LICENSE file that
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    13
 * accompanied this code).
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    14
 *
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    15
 * You should have received a copy of the GNU General Public License version
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    16
 * 2 along with this work; if not, write to the Free Software Foundation,
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    17
 * Inc., 51 Franklin St, Fifth Floor, Boston, MA 02110-1301 USA.
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    18
 *
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    19
 * Please contact Oracle, 500 Oracle Parkway, Redwood Shores, CA 94065 USA
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    20
 * or visit www.oracle.com if you need additional information or have any
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    21
 * questions.
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    22
 *
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    23
 */
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    24
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    25
#include "precompiled.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    26
#include "logging/log.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    27
#include "jfr/jfrEvents.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    28
#include "jfr/periodic/jfrThreadCPULoadEvent.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    29
#include "jfr/support/jfrThreadId.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    30
#include "jfr/support/jfrThreadLocal.hpp"
58863
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
    31
#include "jfr/utilities/jfrThreadIterator.hpp"
50113
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    32
#include "jfr/utilities/jfrTime.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    33
#include "utilities/globalDefinitions.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    34
#include "runtime/os.hpp"
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    35
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    36
jlong JfrThreadCPULoadEvent::get_wallclock_time() {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    37
  return os::javaTimeNanos();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    38
}
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    39
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    40
int JfrThreadCPULoadEvent::_last_active_processor_count = 0;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    41
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    42
int JfrThreadCPULoadEvent::get_processor_count() {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    43
  int cur_processor_count = os::active_processor_count();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    44
  int last_processor_count = _last_active_processor_count;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    45
  _last_active_processor_count = cur_processor_count;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    46
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    47
  // If the number of processors decreases, we don't know at what point during
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    48
  // the sample interval this happened, so use the largest number to try
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    49
  // to avoid percentages above 100%
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    50
  return MAX2(cur_processor_count, last_processor_count);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    51
}
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    52
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    53
// Returns false if the thread has not been scheduled since the last call to updateEvent
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    54
// (i.e. the delta for both system and user time is 0 milliseconds)
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    55
bool JfrThreadCPULoadEvent::update_event(EventThreadCPULoad& event, JavaThread* thread, jlong cur_wallclock_time, int processor_count) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    56
  JfrThreadLocal* const tl = thread->jfr_thread_local();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    57
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    58
  jlong cur_cpu_time = os::thread_cpu_time(thread, true);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    59
  jlong prev_cpu_time = tl->get_cpu_time();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    60
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    61
  jlong prev_wallclock_time = tl->get_wallclock_time();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    62
  tl->set_wallclock_time(cur_wallclock_time);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    63
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    64
  // Threshold of 1 ms
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    65
  if (cur_cpu_time - prev_cpu_time < 1 * NANOSECS_PER_MILLISEC) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    66
    return false;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    67
  }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    68
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    69
  jlong cur_user_time = os::thread_cpu_time(thread, false);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    70
  jlong prev_user_time = tl->get_user_time();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    71
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    72
  jlong cur_system_time = cur_cpu_time - cur_user_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    73
  jlong prev_system_time = prev_cpu_time - prev_user_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    74
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    75
  // The user and total cpu usage clocks can have different resolutions, which can
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    76
  // make us see decreasing system time. Ensure time doesn't go backwards.
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    77
  if (prev_system_time > cur_system_time) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    78
    cur_cpu_time += prev_system_time - cur_system_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    79
    cur_system_time = prev_system_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    80
  }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    81
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    82
  jlong user_time = cur_user_time - prev_user_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    83
  jlong system_time = cur_system_time - prev_system_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    84
  jlong wallclock_time = cur_wallclock_time - prev_wallclock_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    85
  jlong total_available_time = wallclock_time * processor_count;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    86
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    87
  // Avoid reporting percentages above the theoretical max
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    88
  if (user_time + system_time > wallclock_time) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    89
    jlong excess = user_time + system_time - wallclock_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    90
    if (user_time > excess) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    91
      user_time -= excess;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    92
      cur_user_time -= excess;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    93
      cur_cpu_time -= excess;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    94
    } else {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    95
      cur_cpu_time -= excess;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    96
      excess -= user_time;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    97
      user_time = 0;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    98
      cur_user_time = 0;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
    99
      system_time -= excess;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   100
    }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   101
  }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   102
  event.set_user(total_available_time > 0 ? (double)user_time / total_available_time : 0);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   103
  event.set_system(total_available_time > 0 ? (double)system_time / total_available_time : 0);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   104
  tl->set_user_time(cur_user_time);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   105
  tl->set_cpu_time(cur_cpu_time);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   106
  return true;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   107
}
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   108
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   109
void JfrThreadCPULoadEvent::send_events() {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   110
  Thread* periodic_thread = Thread::current();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   111
  JfrThreadLocal* const periodic_thread_tl = periodic_thread->jfr_thread_local();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   112
  traceid periodic_thread_id = periodic_thread_tl->thread_id();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   113
  const int processor_count = JfrThreadCPULoadEvent::get_processor_count();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   114
  JfrTicks event_time = JfrTicks::now();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   115
  jlong cur_wallclock_time = JfrThreadCPULoadEvent::get_wallclock_time();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   116
58863
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   117
  JfrJavaThreadIterator iter;
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   118
  int number_of_threads = 0;
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   119
  while (iter.has_next()) {
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   120
    JavaThread* const jt = iter.next();
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   121
    assert(jt != NULL, "invariant");
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   122
    ++number_of_threads;
50113
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   123
    EventThreadCPULoad event(UNTIMED);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   124
    if (JfrThreadCPULoadEvent::update_event(event, jt, cur_wallclock_time, processor_count)) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   125
      event.set_starttime(event_time);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   126
      if (jt != periodic_thread) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   127
        // Commit reads the thread id from this thread's trace data, so put it there temporarily
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   128
        periodic_thread_tl->set_thread_id(JFR_THREAD_ID(jt));
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   129
      } else {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   130
        periodic_thread_tl->set_thread_id(periodic_thread_id);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   131
      }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   132
      event.commit();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   133
    }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   134
  }
58863
c16ac7a2eba4 8226511: Implement JFR Event Streaming
mgronlun
parents: 50113
diff changeset
   135
  log_trace(jfr)("Measured CPU usage for %d threads in %.3f milliseconds", number_of_threads,
50113
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   136
    (double)(JfrTicks::now() - event_time).milliseconds());
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   137
  // Restore this thread's thread id
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   138
  periodic_thread_tl->set_thread_id(periodic_thread_id);
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   139
}
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   140
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   141
void JfrThreadCPULoadEvent::send_event_for_thread(JavaThread* jt) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   142
  EventThreadCPULoad event;
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   143
  if (event.should_commit()) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   144
    if (update_event(event, jt, get_wallclock_time(), get_processor_count())) {
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   145
      event.commit();
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   146
    }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   147
  }
caf115bb98ad 8199712: Flight Recorder
egahlin
parents:
diff changeset
   148
}