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