/* * This file is part of libplacebo. * * libplacebo is free software; you can redistribute it and/or * modify it under the terms of the GNU Lesser General Public * License as published by the Free Software Foundation; either * version 2.1 of the License, or (at your option) any later version. * * libplacebo is distributed in the hope that it will be useful, * but WITHOUT ANY WARRANTY; without even the implied warranty of * MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE. See the * GNU Lesser General Public License for more details. * * You should have received a copy of the GNU Lesser General Public * License along with libplacebo. If not, see . */ #include #include #include "common.h" #include "log.h" #include "pl_thread.h" struct priv { pl_mutex lock; enum pl_log_level log_level_cap; pl_str logbuffer; }; pl_log pl_log_create(int api_ver, const struct pl_log_params *params) { (void) api_ver; struct pl_log_t *log = pl_zalloc_obj(NULL, log, struct priv); struct priv *p = PL_PRIV(log); log->params = *PL_DEF(params, &pl_log_default_params); pl_mutex_init(&p->lock); pl_info(log, "Initialized libplacebo %s (API v%d)", PL_VERSION, PL_API_VER); return log; } const struct pl_log_params pl_log_default_params = {0}; void pl_log_destroy(pl_log *plog) { pl_log log = *plog; if (!log) return; struct priv *p = PL_PRIV(log); pl_mutex_destroy(&p->lock); pl_free((void *) log); *plog = NULL; } struct pl_log_params pl_log_update(pl_log ptr, const struct pl_log_params *params) { struct pl_log_t *log = (struct pl_log_t *) ptr; if (!log) return pl_log_default_params; struct priv *p = PL_PRIV(log); pl_mutex_lock(&p->lock); struct pl_log_params prev_params = log->params; log->params = *PL_DEF(params, &pl_log_default_params); pl_mutex_unlock(&p->lock); return prev_params; } enum pl_log_level pl_log_level_update(pl_log ptr, enum pl_log_level level) { struct pl_log_t *log = (struct pl_log_t *) ptr; if (!log) return PL_LOG_NONE; struct priv *p = PL_PRIV(log); pl_mutex_lock(&p->lock); enum pl_log_level prev_level = log->params.log_level; log->params.log_level = level; pl_mutex_unlock(&p->lock); return prev_level; } void pl_log_level_cap(pl_log log, enum pl_log_level cap) { if (!log) return; struct priv *p = PL_PRIV(log); pl_mutex_lock(&p->lock); p->log_level_cap = cap; pl_mutex_unlock(&p->lock); } static FILE *default_stream(void *stream, enum pl_log_level level) { return PL_DEF(stream, level <= PL_LOG_WARN ? stderr : stdout); } void pl_log_simple(void *stream, enum pl_log_level level, const char *msg) { static const char *prefix[] = { [PL_LOG_FATAL] = "fatal", [PL_LOG_ERR] = "error", [PL_LOG_WARN] = "warn", [PL_LOG_INFO] = "info", [PL_LOG_DEBUG] = "debug", [PL_LOG_TRACE] = "trace", }; FILE *h = default_stream(stream, level); fprintf(h, "%5s: %s\n", prefix[level], msg); if (level <= PL_LOG_WARN) fflush(h); } void pl_log_color(void *stream, enum pl_log_level level, const char *msg) { static const char *color[] = { [PL_LOG_FATAL] = "31;1", // bright red [PL_LOG_ERR] = "31", // red [PL_LOG_WARN] = "33", // yellow/orange [PL_LOG_INFO] = "32", // green [PL_LOG_DEBUG] = "34", // blue [PL_LOG_TRACE] = "30;1", // bright black }; FILE *h = default_stream(stream, level); fprintf(h, "\033[%sm%s\033[0m\n", color[level], msg); if (level <= PL_LOG_WARN) fflush(h); } static void pl_msg_va(pl_log log, enum pl_log_level lev, const char *fmt, va_list va) { // Test log message without taking the lock, to avoid thrashing the // lock for thousands of trace messages unless those are actually // enabled. This may be a false negative, in which case log messages may // be lost as a result. But this shouldn't be a big deal, since any // situation leading to lost log messages would itself be a race condition. if (!pl_msg_test(log, lev)) return; // Re-test the log message level with held lock to avoid false positives, // which would be a considerably bigger deal than false negatives struct priv *p = PL_PRIV(log); pl_mutex_lock(&p->lock); // Apply this cap before re-testing the log level, to avoid giving users // messages that should have been dropped by the log level. lev = PL_MAX(lev, p->log_level_cap); if (!pl_msg_test(log, lev)) goto done; p->logbuffer.len = 0; pl_str_append_vasprintf((void *) log, &p->logbuffer, fmt, va); log->params.log_cb(log->params.log_priv, lev, (char *) p->logbuffer.buf); done: pl_mutex_unlock(&p->lock); } void pl_msg(pl_log log, enum pl_log_level lev, const char *fmt, ...) { va_list va; va_start(va, fmt); pl_msg_va(log, lev, fmt, va); va_end(va); } void pl_msg_source(pl_log log, enum pl_log_level lev, const char *src) { if (!pl_msg_test(log, lev) || !src) return; int line = 1; while (*src) { const char *end = strchr(src, '\n'); if (!end) { pl_msg(log, lev, "[%3d] %s", line, src); break; } pl_msg(log, lev, "[%3d] %.*s", line, (int)(end - src), src); src = end + 1; line++; } } #ifdef PL_HAVE_DBGHELP #include #include #include // https://github.com/llvm/llvm-project/blob/f03cd763384bbb67ddfa12957859ed58841d4b34/compiler-rt/lib/sanitizer_common/sanitizer_stacktrace.h#L85-L106 static inline uintptr_t get_prev_inst_pc(uintptr_t pc) { #if defined(__arm__) // T32 (Thumb) branch instructions might be 16 or 32 bit long, // so we return (pc-2) in that case in order to be safe. // For A32 mode we return (pc-4) because all instructions are 32 bit long. return (pc - 3) & (~1); #elif defined(__x86_64__) || defined(__i386__) return pc - 1; #else return pc - 4; #endif } static DWORD64 get_preferred_base(const char *module) { DWORD64 image_base = 0; HANDLE file_mapping = NULL; HANDLE file_view = NULL; HANDLE file = CreateFile(module, GENERIC_READ, FILE_SHARE_READ, NULL, OPEN_EXISTING, FILE_ATTRIBUTE_NORMAL, 0); if (file == INVALID_HANDLE_VALUE) goto done; file_mapping = CreateFileMapping(file, NULL, PAGE_READONLY, 0, 0, NULL); if (file_mapping == NULL) goto done; file_view = MapViewOfFile(file_mapping, FILE_MAP_READ, 0, 0, 0); if (file_view == NULL) goto done; PIMAGE_DOS_HEADER dos_header = (PIMAGE_DOS_HEADER) file_view; if (dos_header->e_magic != IMAGE_DOS_SIGNATURE) goto done; PIMAGE_NT_HEADERS pe_header = (PIMAGE_NT_HEADERS) ((char *) file_view + dos_header->e_lfanew); if (pe_header->Signature != IMAGE_NT_SIGNATURE) goto done; if (pe_header->FileHeader.SizeOfOptionalHeader != sizeof(pe_header->OptionalHeader)) goto done; image_base = pe_header->OptionalHeader.ImageBase; done: if (file_view) UnmapViewOfFile(file_view); if (file_mapping) CloseHandle(file_mapping); if (file != INVALID_HANDLE_VALUE) CloseHandle(file); return image_base; } void pl_log_stack_trace(pl_log log, enum pl_log_level lev) { if (!pl_msg_test(log, lev)) return; void *tmp = pl_tmp(NULL); PL_ARRAY(void *) frames = {0}; size_t capacity = 16; do { capacity *= 2; PL_ARRAY_RESIZE(tmp, frames, capacity); // Skip first frame, we don't care about this function frames.num = CaptureStackBackTrace(1, capacity, frames.elem, NULL); } while (capacity == frames.num); if (!frames.num) { pl_free(tmp); return; } // Load dbghelp on demand. While it is available on all Windows versions, // no need to keep it loaded all the time as stack trace printing function, // in theory should be used repetitively rarely. HANDLE process = GetCurrentProcess(); HMODULE dbghelp = LoadLibrary("dbghelp.dll"); DWORD options; SYMBOL_INFO *symbol = NULL; BOOL use_dbghelp = !!dbghelp; #define DBGHELP_SYM(sym) \ __typeof__(&sym) p##sym = (__typeof__(&sym))(void *) GetProcAddress(dbghelp, #sym); \ use_dbghelp &= !!p##sym DBGHELP_SYM(SymCleanup); DBGHELP_SYM(SymFromAddr); DBGHELP_SYM(SymGetLineFromAddr64); DBGHELP_SYM(SymGetModuleInfo64); DBGHELP_SYM(SymGetOptions); DBGHELP_SYM(SymGetSearchPathW); DBGHELP_SYM(SymInitialize); DBGHELP_SYM(SymSetOptions); DBGHELP_SYM(SymSetSearchPathW); #undef DBGHELP_SYM struct priv *p = PL_PRIV(log); PL_ARRAY(wchar_t) base_search = { .num = 1024 }; if (use_dbghelp) { // DbgHelp is not thread-safe. Note that on Windows mutex is recursive, // so no need to unlock before calling pl_msg. pl_mutex_lock(&p->lock); options = pSymGetOptions(); pSymSetOptions(SYMOPT_UNDNAME | SYMOPT_DEFERRED_LOADS | SYMOPT_LOAD_LINES | SYMOPT_FAVOR_COMPRESSED); use_dbghelp &= pSymInitialize(process, NULL, TRUE); if (use_dbghelp) { symbol = pl_alloc(tmp, sizeof(SYMBOL_INFO) + 512); symbol->SizeOfStruct = sizeof(SYMBOL_INFO); symbol->MaxNameLen = 512; PL_ARRAY_RESIZE(tmp, base_search, base_search.num); BOOL ret = pSymGetSearchPathW(process, base_search.elem, base_search.num); base_search.num = ret ? wcslen(base_search.elem) : 0; PL_ARRAY_APPEND(tmp, base_search, L'\0'); } else { pSymSetOptions(options); pl_mutex_unlock(&p->lock); } } pl_msg(log, lev, " Backtrace:"); for (int n = 0; n < frames.num; n++) { uintptr_t pc = get_prev_inst_pc((uintptr_t) frames.elem[n]); pl_str out = {0}; pl_str_append_asprintf(tmp, &out, " #%-2d 0x%"PRIxPTR, n, pc); MEMORY_BASIC_INFORMATION meminfo = {0}; char module_path[MAX_PATH] = {0}; if (VirtualQuery((LPCVOID) pc, &meminfo, sizeof(meminfo))) { DWORD sz = GetModuleFileNameA(meminfo.AllocationBase, module_path, sizeof(module_path)); if (sz == sizeof(module_path)) pl_msg(log, PL_LOG_ERR, "module path truncated"); if (use_dbghelp) { // According to documentation it should search in "The directory // that contains the corresponding module.", but it doesn't appear // to work, so manually set the path to module path. // https://learn.microsoft.com/windows/win32/debug/symbol-paths PL_ARRAY(wchar_t) mod_search = { .num = MAX_PATH }; PL_ARRAY_RESIZE(tmp, mod_search, mod_search.num); sz = GetModuleFileNameW(meminfo.AllocationBase, mod_search.elem, mod_search.num); if (sz > 0 && sz != MAX_PATH && // TODO: Replace with PathCchRemoveFileSpec once mingw-w64 // >= 8.0.1 is commonly available, at the time of writing // there are a few high profile Linux distributions that ship // 8.0.0. PathRemoveFileSpecW(mod_search.elem)) { mod_search.num = wcslen(mod_search.elem); PL_ARRAY_APPEND(tmp, mod_search, L';'); PL_ARRAY_CONCAT(tmp, mod_search, base_search); pSymSetSearchPathW(process, mod_search.elem); } } } DWORD64 sym_displacement; if (use_dbghelp && pSymFromAddr(process, pc, &sym_displacement, symbol)) pl_str_append_asprintf(tmp, &out, " in %s+0x%llx", symbol->Name, sym_displacement); DWORD line_displacement; IMAGEHLP_LINE64 line = {sizeof(line)}; if (use_dbghelp && pSymGetLineFromAddr64(process, pc, &line_displacement, &line)) { pl_str_append_asprintf(tmp, &out, " %s:%lu+0x%lx", line.FileName, line.LineNumber, line_displacement); goto done; } // LLVM tools by convention use absolute addresses with "prefered" base // image offset. We need to read this offset from binary, because due to // ASLR we are not loaded at this base. While Windows tools like WinDbg // expect relative offset to image base. So to be able to easily use it // with both worlds, print both values. DWORD64 module_base = get_preferred_base(module_path); pl_str_append_asprintf(tmp, &out, " (%s+0x%"PRIxPTR") (0x%llx)", module_path, pc - (uintptr_t) meminfo.AllocationBase, module_base + (pc - (uintptr_t) meminfo.AllocationBase)); done: pl_msg(log, lev, "%s", out.buf); } if (use_dbghelp) { pSymSetOptions(options); pSymCleanup(process); pl_mutex_unlock(&p->lock); } // Unload dbghelp. Maybe it is better to keep it loaded? if (dbghelp) FreeLibrary(dbghelp); pl_free(tmp); } #elif defined(PL_HAVE_UNWIND) #define UNW_LOCAL_ONLY #include #include void pl_log_stack_trace(pl_log log, enum pl_log_level lev) { if (!pl_msg_test(log, lev)) return; unw_cursor_t cursor; unw_context_t uc; unw_word_t ip, off; unw_getcontext(&uc); unw_init_local(&cursor, &uc); int depth = 0; pl_msg(log, lev, " Backtrace:"); while (unw_step(&cursor) > 0) { char symbol[256] = ""; Dl_info info = { .dli_fname = "", }; unw_get_reg(&cursor, UNW_REG_IP, &ip); unw_get_proc_name(&cursor, symbol, sizeof(symbol), &off); dladdr((void *) (uintptr_t) ip, &info); pl_msg(log, lev, " #%-2d 0x%016" PRIxPTR " in %s+0x%" PRIxPTR" at %s+0x%" PRIxPTR, depth++, ip, symbol, off, info.dli_fname, ip - (uintptr_t) info.dli_fbase); } } #elif defined(PL_HAVE_EXECINFO) #include void pl_log_stack_trace(pl_log log, enum pl_log_level lev) { if (!pl_msg_test(log, lev)) return; PL_ARRAY(void *) buf = {0}; size_t buf_avail = 16; do { buf_avail *= 2; PL_ARRAY_RESIZE(NULL, buf, buf_avail); buf.num = backtrace(buf.elem, buf_avail); } while (buf.num == buf_avail); pl_msg(log, lev, " Backtrace:"); char **strings = backtrace_symbols(buf.elem, buf.num); for (int i = 1; i < buf.num; i++) pl_msg(log, lev, " #%-2d %s", i - 1, strings[i]); free(strings); pl_free(buf.elem); } #else void pl_log_stack_trace(pl_log log, enum pl_log_level lev) { } #endif