375 lines
9.2 KiB
C++
375 lines
9.2 KiB
C++
/// \author Леонид Юрьев aka Leonid Yuriev <leo@yuriev.ru> \date 2015-2024
|
|
/// \copyright SPDX-License-Identifier: Apache-2.0
|
|
|
|
#include "test.h++"
|
|
|
|
static void fflushall() { fflush(nullptr); }
|
|
|
|
void failure(const char *fmt, ...) {
|
|
va_list ap;
|
|
va_start(ap, fmt);
|
|
fflushall();
|
|
logging::output_nocheckloglevel_ap(logging::failure, fmt, ap);
|
|
va_end(ap);
|
|
fflushall();
|
|
exit(EXIT_FAILURE);
|
|
}
|
|
|
|
const char *test_strerror(int errnum) {
|
|
static __thread char buf[1024];
|
|
return mdbx_strerror_r(errnum, buf, sizeof(buf));
|
|
}
|
|
|
|
MDBX_NORETURN void failure_perror(const char *what, int errnum) {
|
|
failure("%s failed: %s (%d)\n", what, test_strerror(errnum), errnum);
|
|
}
|
|
|
|
//-----------------------------------------------------------------------------
|
|
|
|
static void mdbx_logger(MDBX_log_level_t priority, const char *function,
|
|
int line, const char *fmt,
|
|
va_list args) MDBX_CXX17_NOEXCEPT {
|
|
if (function) {
|
|
if (priority == MDBX_LOG_FATAL)
|
|
log_error("mdbx: fatal failure: %s, %d", function, line);
|
|
logging::output_nocheckloglevel(
|
|
logging::loglevel(priority),
|
|
strncmp(function, "mdbx_", 5) == 0 ? "%s: " : "mdbx %s: ", function);
|
|
logging::feed_ap(fmt, args);
|
|
} else
|
|
logging::feed_ap(fmt, args);
|
|
}
|
|
|
|
namespace logging {
|
|
|
|
/* логирование может быть вызвано после деструкторов */
|
|
static char prefix_buf[64];
|
|
static size_t prefix_len;
|
|
static std::string suffix_buf;
|
|
static const char *suffix_ptr = "~~~";
|
|
struct suffix_cleaner {
|
|
suffix_cleaner() { suffix_ptr = ""; }
|
|
~suffix_cleaner() { suffix_ptr = "~~~"; }
|
|
} static anchor;
|
|
|
|
static loglevel level;
|
|
static FILE *flow;
|
|
|
|
void setlevel(loglevel priority) {
|
|
level = priority;
|
|
int rc = mdbx_setup_debug(MDBX_log_level_t(priority),
|
|
MDBX_DBG_ASSERT | MDBX_DBG_AUDIT | MDBX_DBG_JITTER |
|
|
MDBX_DBG_DUMP,
|
|
mdbx_logger);
|
|
log_trace("set mdbx debug-opts: 0x%02x", rc);
|
|
}
|
|
|
|
void setup(const std::string &prefix) {
|
|
prefix_len = std::min(prefix.size(), sizeof(prefix_buf) - 1);
|
|
memcpy(prefix_buf, prefix.data(), prefix_len);
|
|
}
|
|
|
|
void setup(loglevel priority, const std::string &prefix) {
|
|
setlevel(priority);
|
|
setup(prefix);
|
|
}
|
|
|
|
const char *level2str(const loglevel alevel) {
|
|
switch (alevel) {
|
|
default:
|
|
return "invalid/unknown";
|
|
case extra:
|
|
return "extra";
|
|
case trace:
|
|
return "trace";
|
|
case debug:
|
|
return "debug";
|
|
case verbose:
|
|
return "verbose";
|
|
case notice:
|
|
return "notice";
|
|
case warning:
|
|
return "warning";
|
|
case error:
|
|
return "error";
|
|
case failure:
|
|
return "failure";
|
|
}
|
|
}
|
|
|
|
bool output(const loglevel priority, const char *format, ...) {
|
|
if (lower(priority, level))
|
|
return false;
|
|
|
|
va_list ap;
|
|
va_start(ap, format);
|
|
output_nocheckloglevel_ap(priority, format, ap);
|
|
va_end(ap);
|
|
return true;
|
|
}
|
|
|
|
void ln() {
|
|
if (flow) {
|
|
putc('\n', flow);
|
|
if (flow != stdout)
|
|
putc('\n', stdout);
|
|
flow = nullptr;
|
|
}
|
|
}
|
|
|
|
void output_nocheckloglevel_ap(const logging::loglevel priority,
|
|
const char *format, va_list ap) {
|
|
ln();
|
|
chrono::time now = chrono::now_realtime();
|
|
struct tm tm;
|
|
#ifdef _MSC_VER
|
|
int rc = _localtime32_s(&tm, (const __time32_t *)&now.utc);
|
|
#elif defined(_WIN32) || defined(_WIN64)
|
|
const time_t time_proxy = now.utc;
|
|
int rc = localtime_s(&tm, &time_proxy);
|
|
#else
|
|
time_t time = now.utc;
|
|
int rc = localtime_r(&time, &tm) ? MDBX_SUCCESS : errno;
|
|
#endif
|
|
if (rc != MDBX_SUCCESS)
|
|
failure_perror("localtime_r()", rc);
|
|
|
|
fprintf(stdout,
|
|
"[ %02d%02d%02d-%02d:%02d:%02d.%06d_%05lu %-10s %.4s ] %s" /* TODO */,
|
|
tm.tm_year - 100, tm.tm_mon + 1, tm.tm_mday, tm.tm_hour, tm.tm_min,
|
|
tm.tm_sec, chrono::fractional2us(now.fractional), (long)osal_getpid(),
|
|
prefix_buf, level2str(priority), suffix_ptr);
|
|
|
|
va_list ones;
|
|
memset(&ones, 0, sizeof(ones)) /* zap MSVC and other goofy compilers */;
|
|
if (same_or_higher(priority, error))
|
|
va_copy(ones, ap);
|
|
vfprintf(stdout, format, ap);
|
|
|
|
size_t len = strlen(format);
|
|
char end = len ? format[len - 1] : '\0';
|
|
|
|
switch (end) {
|
|
default:
|
|
putc('\n', stdout);
|
|
break;
|
|
case '\n':
|
|
break;
|
|
case ' ':
|
|
case '_':
|
|
case ':':
|
|
case '|':
|
|
case ',':
|
|
case '\t':
|
|
case '\b':
|
|
case '\r':
|
|
case '\0':
|
|
flow = stdout;
|
|
break;
|
|
}
|
|
|
|
if (same_or_higher(priority, error)) {
|
|
if (flow)
|
|
flow = stderr;
|
|
fprintf(stderr, "[ %05lu %-10s %.4s ] %s", (long)osal_getpid(), prefix_buf,
|
|
level2str(priority), suffix_ptr);
|
|
vfprintf(stderr, format, ones);
|
|
va_end(ones);
|
|
}
|
|
}
|
|
|
|
bool feed_ap(const char *format, va_list ap) {
|
|
if (!flow)
|
|
return false;
|
|
|
|
if (flow == stderr) {
|
|
va_list ones;
|
|
va_copy(ones, ap);
|
|
vfprintf(stdout, format, ones);
|
|
va_end(ones);
|
|
}
|
|
vfprintf(flow, format, ap);
|
|
size_t len = strlen(format);
|
|
if (len && format[len - 1] == '\n')
|
|
flow = nullptr;
|
|
return true;
|
|
}
|
|
|
|
bool feed(const char *format, ...) {
|
|
if (!flow)
|
|
return false;
|
|
|
|
va_list ap;
|
|
va_start(ap, format);
|
|
feed_ap(format, ap);
|
|
va_end(ap);
|
|
return true;
|
|
}
|
|
|
|
local_suffix::local_suffix(const char *c_str)
|
|
: trim_pos(suffix_buf.size()), indent(0) {
|
|
suffix_buf.append(c_str);
|
|
suffix_ptr = suffix_buf.c_str();
|
|
}
|
|
|
|
local_suffix::local_suffix(const std::string &str)
|
|
: trim_pos(suffix_buf.size()), indent(0) {
|
|
suffix_buf.append(str);
|
|
suffix_ptr = suffix_buf.c_str();
|
|
}
|
|
|
|
void local_suffix::push() {
|
|
indent += 1;
|
|
suffix_buf.push_back('\t');
|
|
suffix_ptr = suffix_buf.c_str();
|
|
}
|
|
|
|
void local_suffix::pop() {
|
|
assert(indent > 0);
|
|
if (indent > 0) {
|
|
indent -= 1;
|
|
suffix_buf.pop_back();
|
|
suffix_ptr = suffix_buf.c_str();
|
|
}
|
|
}
|
|
|
|
local_suffix::~local_suffix() {
|
|
suffix_buf.erase(trim_pos);
|
|
suffix_ptr = suffix_buf.c_str();
|
|
}
|
|
|
|
void progress_canary(bool active) {
|
|
static chrono::time progress_timestamp;
|
|
chrono::time now = chrono::now_monotonic();
|
|
|
|
if (now.fixedpoint - progress_timestamp.fixedpoint <
|
|
chrono::from_ms(42).fixedpoint)
|
|
return;
|
|
|
|
if (osal_progress_push(active)) {
|
|
progress_timestamp = now;
|
|
return;
|
|
}
|
|
|
|
if (progress_timestamp.fixedpoint == 0) {
|
|
putc('>', stderr);
|
|
progress_timestamp = now;
|
|
} else if (global::config::console_mode) {
|
|
if (active) {
|
|
static int last_point = -1;
|
|
int point = (now.fixedpoint >> 29) & 3;
|
|
if (point != last_point) {
|
|
progress_timestamp = now;
|
|
fprintf(stderr, "%c\b", "-\\|/"[last_point = point]);
|
|
}
|
|
} else if (now.fixedpoint - progress_timestamp.fixedpoint >
|
|
chrono::from_seconds(2).fixedpoint) {
|
|
progress_timestamp = now;
|
|
fprintf(stderr, "%c\b", "@*"[now.utc & 1]);
|
|
}
|
|
} else {
|
|
static int count;
|
|
if (active && now.fixedpoint - progress_timestamp.fixedpoint >
|
|
chrono::from_seconds(1).fixedpoint) {
|
|
putc('.', stderr);
|
|
progress_timestamp = now;
|
|
++count;
|
|
} else if (now.fixedpoint - progress_timestamp.fixedpoint >
|
|
chrono::from_seconds(5).fixedpoint) {
|
|
putc("@*"[now.utc & 1], stderr);
|
|
progress_timestamp = now;
|
|
++count;
|
|
}
|
|
if (count == 60) {
|
|
count = 0;
|
|
putc('\n', stderr);
|
|
}
|
|
}
|
|
fflush(stderr);
|
|
}
|
|
|
|
} // namespace logging
|
|
|
|
void log_extra(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::extra, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::extra, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_trace(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::trace, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::trace, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_debug(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::debug, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::debug, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_verbose(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::verbose, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::verbose, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_notice(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::notice, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::notice, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_warning(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::warning, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::warning, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_error(const char *msg, ...) {
|
|
logging::ln();
|
|
if (logging::same_or_higher(logging::error, logging::level)) {
|
|
va_list ap;
|
|
va_start(ap, msg);
|
|
logging::output_nocheckloglevel_ap(logging::error, msg, ap);
|
|
va_end(ap);
|
|
}
|
|
}
|
|
|
|
void log_trouble(const char *where, const char *what, int errnum) {
|
|
log_error("%s: %s %s", where, what, test_strerror(errnum));
|
|
}
|
|
|
|
bool log_enabled(const logging::loglevel priority) {
|
|
return logging::same_or_higher(priority, logging::level);
|
|
}
|
|
|
|
void log_flush(void) {
|
|
logging::ln();
|
|
fflushall();
|
|
}
|