yuzu/src/common/logging/backend.cpp

349 lines
17 KiB
C++
Raw Normal View History

2014-10-28 07:36:00 +00:00
// Copyright 2014 Citra Emulator Project
2014-12-17 05:38:14 +00:00
// Licensed under GPLv2 or any later version
2014-10-28 07:36:00 +00:00
// Refer to the license.txt file included.
2018-07-02 17:10:41 +00:00
#include <algorithm>
#include <atomic>
2018-07-02 17:10:41 +00:00
#include <chrono>
#include <climits>
2018-07-02 17:10:41 +00:00
#include <condition_variable>
#include <memory>
#include <mutex>
2018-07-02 17:10:41 +00:00
#include <thread>
#include <vector>
2018-07-02 17:10:41 +00:00
#ifdef _WIN32
#include <share.h> // For _SH_DENYWR
#include <windows.h> // For OutputDebugStringW
2018-07-02 17:10:41 +00:00
#else
#define _SH_DENYWR 0
#endif
#include "common/assert.h"
#include "common/logging/backend.h"
2014-10-28 07:36:00 +00:00
#include "common/logging/log.h"
#include "common/logging/text_formatter.h"
#include "common/settings.h"
#include "common/string_util.h"
2018-07-02 17:10:41 +00:00
#include "common/threadsafe_queue.h"
2014-10-28 07:36:00 +00:00
namespace Common::Log {
2014-10-28 07:36:00 +00:00
2018-07-02 17:10:41 +00:00
/**
* Static state as a singleton.
*/
class Impl {
public:
static Impl& Instance() {
static Impl backend;
return backend;
}
Impl(const Impl&) = delete;
Impl& operator=(const Impl&) = delete;
Impl(Impl&&) = delete;
Impl& operator=(Impl&&) = delete;
2018-07-02 17:10:41 +00:00
void PushEntry(Class log_class, Level log_level, const char* filename, unsigned int line_num,
const char* function, std::string message) {
message_queue.Push(
CreateEntry(log_class, log_level, filename, line_num, function, std::move(message)));
2018-07-02 17:10:41 +00:00
}
void AddBackend(std::unique_ptr<Backend> backend) {
std::lock_guard lock{writing_mutex};
2018-07-02 17:10:41 +00:00
backends.push_back(std::move(backend));
}
void RemoveBackend(std::string_view backend_name) {
std::lock_guard lock{writing_mutex};
const auto it =
std::remove_if(backends.begin(), backends.end(),
[&backend_name](const auto& i) { return backend_name == i->GetName(); });
2018-07-02 17:10:41 +00:00
backends.erase(it, backends.end());
}
const Filter& GetGlobalFilter() const {
return filter;
}
void SetGlobalFilter(const Filter& f) {
filter = f;
}
Backend* GetBackend(std::string_view backend_name) {
const auto it =
std::find_if(backends.begin(), backends.end(),
[&backend_name](const auto& i) { return backend_name == i->GetName(); });
2018-07-02 17:10:41 +00:00
if (it == backends.end())
return nullptr;
return it->get();
}
private:
Impl() {
backend_thread = std::thread([&] {
Entry entry;
auto write_logs = [&](Entry& e) {
std::lock_guard lock{writing_mutex};
2018-07-02 17:10:41 +00:00
for (const auto& backend : backends) {
backend->Write(e);
}
};
2018-10-08 21:28:54 +00:00
while (true) {
entry = message_queue.PopWait();
if (entry.final_entry) {
2018-07-02 17:10:41 +00:00
break;
}
write_logs(entry);
}
2018-07-02 17:10:41 +00:00
// Drain the logging queue. Only writes out up to MAX_LOGS_TO_WRITE to prevent a case
// where a system is repeatedly spamming logs even on close.
const int MAX_LOGS_TO_WRITE = filter.IsDebug() ? INT_MAX : 100;
2018-07-02 17:10:41 +00:00
int logs_written = 0;
while (logs_written++ < MAX_LOGS_TO_WRITE && message_queue.Pop(entry)) {
write_logs(entry);
}
});
}
~Impl() {
Entry entry;
entry.final_entry = true;
message_queue.Push(entry);
2018-07-02 17:10:41 +00:00
backend_thread.join();
}
Entry CreateEntry(Class log_class, Level log_level, const char* filename, unsigned int line_nr,
const char* function, std::string message) const {
using std::chrono::duration_cast;
using std::chrono::microseconds;
using std::chrono::steady_clock;
return {
.timestamp = duration_cast<microseconds>(steady_clock::now() - time_origin),
.log_class = log_class,
.log_level = log_level,
.filename = filename,
.line_num = line_nr,
.function = function,
.message = std::move(message),
.final_entry = false,
};
}
std::mutex writing_mutex;
2018-07-02 17:10:41 +00:00
std::thread backend_thread;
std::vector<std::unique_ptr<Backend>> backends;
MPSCQueue<Entry> message_queue;
2018-07-02 17:10:41 +00:00
Filter filter;
std::chrono::steady_clock::time_point time_origin{std::chrono::steady_clock::now()};
2018-07-02 17:10:41 +00:00
};
void ConsoleBackend::Write(const Entry& entry) {
PrintMessage(entry);
}
void ColorConsoleBackend::Write(const Entry& entry) {
PrintColoredMessage(entry);
}
FileBackend::FileBackend(const std::string& filename) : bytes_written(0) {
if (FS::Exists(filename + ".old.txt")) {
FS::Delete(filename + ".old.txt");
}
if (FS::Exists(filename)) {
FS::Rename(filename, filename + ".old.txt");
}
// _SH_DENYWR allows read only access to the file for other programs.
// It is #defined to 0 on other platforms
file = FS::IOFile(filename, "w", _SH_DENYWR);
}
2018-07-02 17:10:41 +00:00
void FileBackend::Write(const Entry& entry) {
// prevent logs from going over the maximum size (in case its spamming and the user doesn't
// know)
constexpr std::size_t MAX_BYTES_WRITTEN = 100 * 1024 * 1024;
constexpr std::size_t MAX_BYTES_WRITTEN_EXTENDED = 1024 * 1024 * 1024;
if (!file.IsOpen()) {
return;
}
if (Settings::values.extended_logging && bytes_written > MAX_BYTES_WRITTEN_EXTENDED) {
return;
} else if (!Settings::values.extended_logging && bytes_written > MAX_BYTES_WRITTEN) {
2018-07-02 17:10:41 +00:00
return;
}
bytes_written += file.WriteString(FormatLogMessage(entry).append(1, '\n'));
2018-07-02 17:10:41 +00:00
if (entry.log_level >= Level::Error) {
file.Flush();
}
}
void DebuggerBackend::Write(const Entry& entry) {
#ifdef _WIN32
::OutputDebugStringW(UTF8ToUTF16W(FormatLogMessage(entry).append(1, '\n')).c_str());
#endif
}
2014-10-28 07:36:00 +00:00
/// Macro listing all log classes. Code should define CLS and SUB as desired before invoking this.
#define ALL_LOG_CLASSES() \
CLS(Log) \
CLS(Common) \
SUB(Common, Filesystem) \
SUB(Common, Memory) \
CLS(Core) \
SUB(Core, ARM) \
SUB(Core, Timing) \
CLS(Config) \
CLS(Debug) \
SUB(Debug, Emulated) \
SUB(Debug, GPU) \
SUB(Debug, Breakpoint) \
SUB(Debug, GDBStub) \
CLS(Kernel) \
SUB(Kernel, SVC) \
CLS(Service) \
SUB(Service, ACC) \
SUB(Service, Audio) \
SUB(Service, AM) \
SUB(Service, AOC) \
SUB(Service, APM) \
SUB(Service, ARP) \
2018-05-28 13:36:38 +00:00
SUB(Service, BCAT) \
SUB(Service, BPC) \
SUB(Service, BGTC) \
SUB(Service, BTDRV) \
SUB(Service, BTM) \
SUB(Service, Capture) \
SUB(Service, ERPT) \
SUB(Service, ETicket) \
SUB(Service, EUPLD) \
2018-03-20 13:55:20 +00:00
SUB(Service, Fatal) \
SUB(Service, FGM) \
SUB(Service, Friend) \
SUB(Service, FS) \
SUB(Service, GRC) \
SUB(Service, HID) \
SUB(Service, IRS) \
SUB(Service, LBL) \
SUB(Service, LDN) \
SUB(Service, LDR) \
SUB(Service, LM) \
SUB(Service, Migration) \
SUB(Service, Mii) \
SUB(Service, MM) \
SUB(Service, NCM) \
SUB(Service, NFC) \
SUB(Service, NFP) \
SUB(Service, NIFM) \
SUB(Service, NIM) \
SUB(Service, NPNS) \
SUB(Service, NS) \
SUB(Service, NVDRV) \
SUB(Service, OLSC) \
SUB(Service, PCIE) \
SUB(Service, PCTL) \
SUB(Service, PCV) \
SUB(Service, PM) \
SUB(Service, PREPO) \
SUB(Service, PSC) \
SUB(Service, PSM) \
SUB(Service, SET) \
SUB(Service, SM) \
2018-03-22 06:54:16 +00:00
SUB(Service, SPL) \
2018-03-23 06:32:50 +00:00
SUB(Service, SSL) \
SUB(Service, TCAP) \
SUB(Service, Time) \
SUB(Service, USB) \
SUB(Service, VI) \
SUB(Service, WLAN) \
CLS(HW) \
SUB(HW, Memory) \
SUB(HW, LCD) \
SUB(HW, GPU) \
2017-01-01 12:58:02 +00:00
SUB(HW, AES) \
2018-01-17 06:09:33 +00:00
CLS(IPC) \
CLS(Frontend) \
CLS(Render) \
SUB(Render, Software) \
SUB(Render, OpenGL) \
SUB(Render, Vulkan) \
CLS(Audio) \
SUB(Audio, DSP) \
SUB(Audio, Sink) \
2017-01-20 19:52:32 +00:00
CLS(Input) \
CLS(Network) \
CLS(Loader) \
CLS(CheatEngine) \
2018-07-28 03:55:23 +00:00
CLS(Crypto) \
CLS(WebService)
2014-10-28 07:36:00 +00:00
// GetClassName is a macro defined by Windows.h, grrr...
const char* GetLogClassName(Class log_class) {
2014-10-28 07:36:00 +00:00
switch (log_class) {
#define CLS(x) \
case Class::x: \
return #x;
#define SUB(x, y) \
case Class::x##_##y: \
return #x "." #y;
2014-10-28 07:36:00 +00:00
ALL_LOG_CLASSES()
#undef CLS
#undef SUB
case Class::Count:
break;
2014-10-28 07:36:00 +00:00
}
return "Invalid";
2014-10-28 07:36:00 +00:00
}
const char* GetLevelName(Level log_level) {
#define LVL(x) \
case Level::x: \
return #x
2014-10-28 07:36:00 +00:00
switch (log_level) {
LVL(Trace);
LVL(Debug);
LVL(Info);
LVL(Warning);
LVL(Error);
LVL(Critical);
case Level::Count:
break;
2014-10-28 07:36:00 +00:00
}
#undef LVL
return "Invalid";
2014-10-28 07:36:00 +00:00
}
2018-07-02 17:10:41 +00:00
void SetGlobalFilter(const Filter& filter) {
Impl::Instance().SetGlobalFilter(filter);
}
void AddBackend(std::unique_ptr<Backend> backend) {
Impl::Instance().AddBackend(std::move(backend));
}
void RemoveBackend(std::string_view backend_name) {
2018-07-02 17:10:41 +00:00
Impl::Instance().RemoveBackend(backend_name);
}
Backend* GetBackend(std::string_view backend_name) {
2018-07-02 17:10:41 +00:00
return Impl::Instance().GetBackend(backend_name);
}
void FmtLogMessageImpl(Class log_class, Level log_level, const char* filename,
unsigned int line_num, const char* function, const char* format,
const fmt::format_args& args) {
auto& instance = Impl::Instance();
const auto& filter = instance.GetGlobalFilter();
2018-07-02 17:10:41 +00:00
if (!filter.CheckMessage(log_class, log_level))
return;
2018-07-02 17:10:41 +00:00
instance.PushEntry(log_class, log_level, filename, line_num, function,
fmt::vformat(format, args));
2014-10-28 07:36:00 +00:00
}
} // namespace Common::Log