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 18:10:41 +01:00
|
|
|
#include <algorithm>
|
2018-07-20 20:31:25 +01:00
|
|
|
#include <atomic>
|
2018-07-02 18:10:41 +01:00
|
|
|
#include <chrono>
|
2018-07-14 19:47:14 +01:00
|
|
|
#include <climits>
|
2018-07-02 18:10:41 +01:00
|
|
|
#include <condition_variable>
|
|
|
|
#include <memory>
|
2018-07-20 20:31:25 +01:00
|
|
|
#include <mutex>
|
2018-07-02 18:10:41 +01:00
|
|
|
#include <thread>
|
2018-07-20 20:31:25 +01:00
|
|
|
#include <vector>
|
2018-07-02 18:10:41 +01:00
|
|
|
#ifdef _WIN32
|
2018-10-05 04:22:49 +01:00
|
|
|
#include <share.h> // For _SH_DENYWR
|
2018-12-07 15:21:18 +00:00
|
|
|
#include <windows.h> // For OutputDebugStringW
|
2018-07-02 18:10:41 +01:00
|
|
|
#else
|
|
|
|
#define _SH_DENYWR 0
|
|
|
|
#endif
|
2015-08-02 17:55:31 +01:00
|
|
|
#include "common/assert.h"
|
2016-09-21 07:52:38 +01:00
|
|
|
#include "common/logging/backend.h"
|
2014-10-28 07:36:00 +00:00
|
|
|
#include "common/logging/log.h"
|
|
|
|
#include "common/logging/text_formatter.h"
|
2018-03-22 10:21:29 +00:00
|
|
|
#include "common/string_util.h"
|
2018-07-02 18:10:41 +01:00
|
|
|
#include "common/threadsafe_queue.h"
|
2020-07-29 18:25:37 +01:00
|
|
|
#include "core/settings.h"
|
2014-10-28 07:36:00 +00:00
|
|
|
|
|
|
|
namespace Log {
|
|
|
|
|
2018-07-02 18:10:41 +01:00
|
|
|
/**
|
|
|
|
* Static state as a singleton.
|
|
|
|
*/
|
|
|
|
class Impl {
|
|
|
|
public:
|
|
|
|
static Impl& Instance() {
|
|
|
|
static Impl backend;
|
|
|
|
return backend;
|
|
|
|
}
|
|
|
|
|
|
|
|
Impl(Impl const&) = delete;
|
|
|
|
const Impl& operator=(Impl const&) = delete;
|
|
|
|
|
2019-03-02 19:25:50 +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 18:10:41 +01:00
|
|
|
}
|
|
|
|
|
|
|
|
void AddBackend(std::unique_ptr<Backend> backend) {
|
2019-04-01 17:29:59 +01:00
|
|
|
std::lock_guard lock{writing_mutex};
|
2018-07-02 18:10:41 +01:00
|
|
|
backends.push_back(std::move(backend));
|
|
|
|
}
|
|
|
|
|
2018-07-20 20:27:17 +01:00
|
|
|
void RemoveBackend(std::string_view backend_name) {
|
2019-04-01 17:29:59 +01:00
|
|
|
std::lock_guard lock{writing_mutex};
|
2018-07-20 20:27:17 +01:00
|
|
|
const auto it =
|
|
|
|
std::remove_if(backends.begin(), backends.end(),
|
|
|
|
[&backend_name](const auto& i) { return backend_name == i->GetName(); });
|
2018-07-02 18:10:41 +01:00
|
|
|
backends.erase(it, backends.end());
|
|
|
|
}
|
|
|
|
|
|
|
|
const Filter& GetGlobalFilter() const {
|
|
|
|
return filter;
|
|
|
|
}
|
|
|
|
|
|
|
|
void SetGlobalFilter(const Filter& f) {
|
|
|
|
filter = f;
|
|
|
|
}
|
|
|
|
|
2018-07-20 20:27:17 +01:00
|
|
|
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 18:10:41 +01:00
|
|
|
if (it == backends.end())
|
|
|
|
return nullptr;
|
|
|
|
return it->get();
|
|
|
|
}
|
|
|
|
|
|
|
|
private:
|
|
|
|
Impl() {
|
|
|
|
backend_thread = std::thread([&] {
|
|
|
|
Entry entry;
|
|
|
|
auto write_logs = [&](Entry& e) {
|
2019-04-01 17:29:59 +01:00
|
|
|
std::lock_guard lock{writing_mutex};
|
2018-07-02 18:10:41 +01:00
|
|
|
for (const auto& backend : backends) {
|
|
|
|
backend->Write(e);
|
|
|
|
}
|
|
|
|
};
|
2018-10-08 22:28:54 +01:00
|
|
|
while (true) {
|
|
|
|
entry = message_queue.PopWait();
|
2018-09-09 12:08:57 +01:00
|
|
|
if (entry.final_entry) {
|
2018-07-02 18:10:41 +01:00
|
|
|
break;
|
|
|
|
}
|
|
|
|
write_logs(entry);
|
|
|
|
}
|
2018-09-09 12:08:57 +01:00
|
|
|
|
2018-07-02 18:10:41 +01: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.
|
2018-07-14 19:47:14 +01:00
|
|
|
const int MAX_LOGS_TO_WRITE = filter.IsDebug() ? INT_MAX : 100;
|
2018-07-02 18:10:41 +01:00
|
|
|
int logs_written = 0;
|
|
|
|
while (logs_written++ < MAX_LOGS_TO_WRITE && message_queue.Pop(entry)) {
|
|
|
|
write_logs(entry);
|
|
|
|
}
|
|
|
|
});
|
|
|
|
}
|
|
|
|
|
|
|
|
~Impl() {
|
2018-09-09 12:08:57 +01:00
|
|
|
Entry entry;
|
|
|
|
entry.final_entry = true;
|
|
|
|
message_queue.Push(entry);
|
2018-07-02 18:10:41 +01:00
|
|
|
backend_thread.join();
|
|
|
|
}
|
|
|
|
|
2019-03-02 19:25:50 +00:00
|
|
|
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;
|
2020-08-03 15:31:57 +01:00
|
|
|
using std::chrono::microseconds;
|
2019-03-02 19:25:50 +00:00
|
|
|
using std::chrono::steady_clock;
|
|
|
|
|
2020-08-03 15:31:57 +01:00
|
|
|
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,
|
|
|
|
};
|
2019-03-02 19:25:50 +00:00
|
|
|
}
|
|
|
|
|
2018-09-09 12:08:57 +01:00
|
|
|
std::mutex writing_mutex;
|
2018-07-02 18:10:41 +01:00
|
|
|
std::thread backend_thread;
|
|
|
|
std::vector<std::unique_ptr<Backend>> backends;
|
|
|
|
Common::MPSCQueue<Log::Entry> message_queue;
|
|
|
|
Filter filter;
|
2019-03-02 19:28:58 +00:00
|
|
|
std::chrono::steady_clock::time_point time_origin{std::chrono::steady_clock::now()};
|
2018-07-02 18:10:41 +01:00
|
|
|
};
|
|
|
|
|
|
|
|
void ConsoleBackend::Write(const Entry& entry) {
|
|
|
|
PrintMessage(entry);
|
|
|
|
}
|
|
|
|
|
|
|
|
void ColorConsoleBackend::Write(const Entry& entry) {
|
|
|
|
PrintColoredMessage(entry);
|
|
|
|
}
|
|
|
|
|
2020-12-31 21:10:01 +00:00
|
|
|
FileBackend::FileBackend(const std::string& filename) : bytes_written(0) {
|
2021-01-03 19:53:23 +00:00
|
|
|
if (FileUtil::Exists(filename + ".old.txt")) {
|
|
|
|
FileUtil::Delete(filename + ".old.txt");
|
|
|
|
}
|
2020-12-31 21:10:01 +00:00
|
|
|
if (FileUtil::Exists(filename)) {
|
2021-01-01 11:17:20 +00:00
|
|
|
FileUtil::Rename(filename, filename + ".old.txt");
|
2020-12-31 21:10:01 +00:00
|
|
|
}
|
|
|
|
|
|
|
|
// _SH_DENYWR allows read only access to the file for other programs.
|
|
|
|
// It is #defined to 0 on other platforms
|
|
|
|
file = FileUtil::IOFile(filename, "w", _SH_DENYWR);
|
|
|
|
}
|
2018-07-02 18:10:41 +01: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)
|
2020-07-29 18:25:37 +01:00
|
|
|
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 18:10:41 +01:00
|
|
|
return;
|
|
|
|
}
|
2020-07-29 18:25:37 +01:00
|
|
|
|
2018-10-05 04:22:49 +01:00
|
|
|
bytes_written += file.WriteString(FormatLogMessage(entry).append(1, '\n'));
|
2018-07-02 18:10:41 +01:00
|
|
|
if (entry.log_level >= Level::Error) {
|
|
|
|
file.Flush();
|
|
|
|
}
|
|
|
|
}
|
|
|
|
|
2018-10-05 04:22:49 +01:00
|
|
|
void DebuggerBackend::Write(const Entry& entry) {
|
|
|
|
#ifdef _WIN32
|
2018-12-07 15:21:18 +00:00
|
|
|
::OutputDebugStringW(Common::UTF8ToUTF16W(FormatLogMessage(entry).append(1, '\n')).c_str());
|
2018-10-05 04:22:49 +01:00
|
|
|
#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.
|
2016-09-18 01:38:01 +01:00
|
|
|
#define ALL_LOG_CLASSES() \
|
|
|
|
CLS(Log) \
|
|
|
|
CLS(Common) \
|
|
|
|
SUB(Common, Filesystem) \
|
|
|
|
SUB(Common, Memory) \
|
|
|
|
CLS(Core) \
|
2017-10-23 05:13:12 +01:00
|
|
|
SUB(Core, ARM) \
|
2016-09-18 01:38:01 +01:00
|
|
|
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) \
|
2018-02-04 21:40:12 +00:00
|
|
|
SUB(Service, ACC) \
|
2018-02-05 03:55:45 +00:00
|
|
|
SUB(Service, Audio) \
|
2018-02-04 21:58:12 +00:00
|
|
|
SUB(Service, AM) \
|
2018-02-20 07:27:32 +00:00
|
|
|
SUB(Service, AOC) \
|
2018-02-05 03:39:47 +00:00
|
|
|
SUB(Service, APM) \
|
2018-08-04 22:54:54 +01:00
|
|
|
SUB(Service, ARP) \
|
2018-05-28 14:36:38 +01:00
|
|
|
SUB(Service, BCAT) \
|
2018-08-01 20:40:55 +01:00
|
|
|
SUB(Service, BPC) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, BTDRV) \
|
2018-07-29 01:59:09 +01:00
|
|
|
SUB(Service, BTM) \
|
2018-08-01 21:24:03 +01:00
|
|
|
SUB(Service, Capture) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, ERPT) \
|
|
|
|
SUB(Service, ETicket) \
|
|
|
|
SUB(Service, EUPLD) \
|
2018-03-20 13:55:20 +00:00
|
|
|
SUB(Service, Fatal) \
|
2018-07-31 12:20:42 +01:00
|
|
|
SUB(Service, FGM) \
|
2018-02-19 22:31:54 +00:00
|
|
|
SUB(Service, Friend) \
|
2016-09-18 01:38:01 +01:00
|
|
|
SUB(Service, FS) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, GRC) \
|
2016-09-18 01:38:01 +01:00
|
|
|
SUB(Service, HID) \
|
2018-09-24 03:33:29 +01:00
|
|
|
SUB(Service, IRS) \
|
2018-07-27 19:01:17 +01:00
|
|
|
SUB(Service, LBL) \
|
2018-07-26 06:16:08 +01:00
|
|
|
SUB(Service, LDN) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, LDR) \
|
2018-02-05 03:41:55 +00:00
|
|
|
SUB(Service, LM) \
|
2018-08-02 14:59:08 +01:00
|
|
|
SUB(Service, Migration) \
|
2018-07-27 20:39:30 +01:00
|
|
|
SUB(Service, Mii) \
|
2018-06-05 10:19:29 +01:00
|
|
|
SUB(Service, MM) \
|
2018-07-27 22:32:45 +01:00
|
|
|
SUB(Service, NCM) \
|
2018-07-27 19:32:39 +01:00
|
|
|
SUB(Service, NFC) \
|
2018-03-30 02:06:51 +01:00
|
|
|
SUB(Service, NFP) \
|
2018-02-05 03:35:42 +00:00
|
|
|
SUB(Service, NIFM) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, NIM) \
|
2018-10-24 03:30:00 +01:00
|
|
|
SUB(Service, NPNS) \
|
2018-02-15 02:43:11 +00:00
|
|
|
SUB(Service, NS) \
|
2018-01-24 04:18:23 +00:00
|
|
|
SUB(Service, NVDRV) \
|
2020-11-19 20:35:07 +00:00
|
|
|
SUB(Service, OLSC) \
|
2018-07-31 11:33:38 +01:00
|
|
|
SUB(Service, PCIE) \
|
2018-02-05 03:44:00 +00:00
|
|
|
SUB(Service, PCTL) \
|
2018-08-01 20:40:55 +01:00
|
|
|
SUB(Service, PCV) \
|
2018-08-08 21:42:06 +01:00
|
|
|
SUB(Service, PM) \
|
2018-04-26 22:19:34 +01:00
|
|
|
SUB(Service, PREPO) \
|
2018-08-02 02:59:22 +01:00
|
|
|
SUB(Service, PSC) \
|
2018-10-24 03:30:00 +01:00
|
|
|
SUB(Service, PSM) \
|
2018-02-05 03:55:45 +00:00
|
|
|
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) \
|
2018-10-24 03:30:00 +01:00
|
|
|
SUB(Service, TCAP) \
|
2018-02-05 03:59:52 +00:00
|
|
|
SUB(Service, Time) \
|
2018-08-07 07:41:43 +01:00
|
|
|
SUB(Service, USB) \
|
2018-02-05 03:26:44 +00:00
|
|
|
SUB(Service, VI) \
|
2018-07-29 02:20:43 +01:00
|
|
|
SUB(Service, WLAN) \
|
2016-09-18 01:38:01 +01:00
|
|
|
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) \
|
2016-09-18 01:38:01 +01:00
|
|
|
CLS(Frontend) \
|
|
|
|
CLS(Render) \
|
|
|
|
SUB(Render, Software) \
|
|
|
|
SUB(Render, OpenGL) \
|
2019-02-12 20:59:04 +00:00
|
|
|
SUB(Render, Vulkan) \
|
2016-09-18 01:38:01 +01:00
|
|
|
CLS(Audio) \
|
|
|
|
SUB(Audio, DSP) \
|
|
|
|
SUB(Audio, Sink) \
|
2017-01-20 19:52:32 +00:00
|
|
|
CLS(Input) \
|
2017-07-07 20:34:15 +01:00
|
|
|
CLS(Network) \
|
2017-06-28 03:29:00 +01:00
|
|
|
CLS(Loader) \
|
2019-05-31 00:32:46 +01:00
|
|
|
CLS(CheatEngine) \
|
2018-07-28 04:55:23 +01:00
|
|
|
CLS(Crypto) \
|
2017-06-28 03:29:00 +01:00
|
|
|
CLS(WebService)
|
2014-10-28 07:36:00 +00:00
|
|
|
|
|
|
|
// GetClassName is a macro defined by Windows.h, grrr...
|
2015-05-12 06:19:44 +01:00
|
|
|
const char* GetLogClassName(Class log_class) {
|
2014-10-28 07:36:00 +00:00
|
|
|
switch (log_class) {
|
2016-09-18 01:38:01 +01:00
|
|
|
#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
|
2016-09-18 01:38:01 +01:00
|
|
|
case Class::Count:
|
2019-11-15 21:43:35 +00:00
|
|
|
break;
|
2014-10-28 07:36:00 +00:00
|
|
|
}
|
2019-11-15 21:43:35 +00:00
|
|
|
return "Invalid";
|
2014-10-28 07:36:00 +00:00
|
|
|
}
|
|
|
|
|
2015-05-12 06:19:44 +01:00
|
|
|
const char* GetLevelName(Level log_level) {
|
2016-09-18 01:38:01 +01:00
|
|
|
#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);
|
2016-09-18 01:38:01 +01:00
|
|
|
case Level::Count:
|
2019-11-15 21:43:35 +00:00
|
|
|
break;
|
2014-10-28 07:36:00 +00:00
|
|
|
}
|
|
|
|
#undef LVL
|
2019-11-15 21:43:35 +00:00
|
|
|
return "Invalid";
|
2014-10-28 07:36:00 +00:00
|
|
|
}
|
|
|
|
|
2018-07-02 18:10:41 +01:00
|
|
|
void SetGlobalFilter(const Filter& filter) {
|
|
|
|
Impl::Instance().SetGlobalFilter(filter);
|
|
|
|
}
|
|
|
|
|
|
|
|
void AddBackend(std::unique_ptr<Backend> backend) {
|
|
|
|
Impl::Instance().AddBackend(std::move(backend));
|
|
|
|
}
|
2015-03-06 18:15:02 +00:00
|
|
|
|
2018-07-20 20:27:17 +01:00
|
|
|
void RemoveBackend(std::string_view backend_name) {
|
2018-07-02 18:10:41 +01:00
|
|
|
Impl::Instance().RemoveBackend(backend_name);
|
|
|
|
}
|
|
|
|
|
2018-07-20 20:27:17 +01:00
|
|
|
Backend* GetBackend(std::string_view backend_name) {
|
2018-07-02 18:10:41 +01:00
|
|
|
return Impl::Instance().GetBackend(backend_name);
|
2015-03-06 18:15:02 +00:00
|
|
|
}
|
|
|
|
|
2018-04-06 05:42:09 +01:00
|
|
|
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) {
|
2018-08-14 02:44:53 +01:00
|
|
|
auto& instance = Impl::Instance();
|
|
|
|
const auto& filter = instance.GetGlobalFilter();
|
2018-07-02 18:10:41 +01:00
|
|
|
if (!filter.CheckMessage(log_class, log_level))
|
2018-03-22 10:21:29 +00:00
|
|
|
return;
|
2018-07-02 18:10:41 +01:00
|
|
|
|
2019-03-02 19:25:50 +00:00
|
|
|
instance.PushEntry(log_class, log_level, filename, line_num, function,
|
|
|
|
fmt::vformat(format, args));
|
2014-10-28 07:36:00 +00:00
|
|
|
}
|
2018-07-14 18:57:13 +01:00
|
|
|
} // namespace Log
|