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