rpcs3/rpcs3/Emu/perf_monitor.cpp

103 lines
2.6 KiB
C++

#include "stdafx.h"
#include "perf_monitor.hpp"
#include "Emu/System.h"
#include "Emu/Cell/timers.hpp"
#include "util/cpu_stats.hpp"
#include "util/sysinfo.hpp"
#include "Utilities/Thread.h"
LOG_CHANNEL(perf_log, "PERF");
void perf_monitor::operator()()
{
constexpr u64 update_interval_us = 500000; // Update every half second
constexpr u64 log_interval_us_max = 10000000; // Log at minimum every 10 seconds
constexpr u64 log_interval_us_min = 500000; // Log at maximum every half a second (catching possible memory leak)
constexpr u64 log_mem_increase = 50 * (1024 * 1024); // Log when memory usage increased by this amount
u64 elapsed_us = 0;
utils::cpu_stats stats;
stats.init_cpu_query();
u32 logged_pause = 0;
u64 last_pause_time = umax;
u64 max_memory_usage = 0;
std::vector<double> per_core_usage;
std::string msg;
for (u64 sleep_until = get_system_time();;)
{
thread_ctrl::wait_until(&sleep_until, update_interval_us);
elapsed_us += update_interval_us;
double total_usage = 0.0;
stats.get_per_core_usage(per_core_usage, total_usage);
const u64 current_mem_use = utils::get_memory_usage().second;
const u64 mem_use_increase = current_mem_use >= max_memory_usage ? current_mem_use - max_memory_usage : 0;
const u64 log_interval = (mem_use_increase >= log_mem_increase ? log_interval_us_min : log_interval_us_max);
if (elapsed_us >= log_interval || thread_ctrl::state() == thread_state::aborting)
{
max_memory_usage = std::max<u64>(current_mem_use, max_memory_usage);
elapsed_us = 0;
const bool is_paused = Emu.IsPaused();
const u64 pause_time = Emu.GetPauseTime();
if (!is_paused || last_pause_time != pause_time)
{
// Resumed or not paused since last check
logged_pause = 0;
last_pause_time = pause_time;
}
if (is_paused)
{
if (logged_pause >= 2)
{
// Let's not spam the log when emulation is paused
// But still emit the message two times so even paused state can be debugged and inspected
continue;
}
logged_pause++;
}
msg.clear();
fmt::append(msg, "CPU Usage: Total: %.1f%%", total_usage);
if (!per_core_usage.empty())
{
fmt::append(msg, ", Cores:");
}
for (usz i = 0; i < per_core_usage.size(); i++)
{
fmt::append(msg, "%s %.1f%%", i > 0 ? "," : "", per_core_usage[i]);
}
if (max_memory_usage)
{
fmt::append(msg, ", RAM Usage: %dMB (Peak: %dMB)", current_mem_use / (1024 * 1024), max_memory_usage / (1024 * 1024));
}
perf_log.notice("%s", msg);
if (thread_ctrl::state() == thread_state::aborting)
{
// Log once before terminating
break;
}
}
}
}
perf_monitor::~perf_monitor()
{
}