238 lines
8.7 KiB
C++
238 lines
8.7 KiB
C++
//
|
|
// Copyright(c) 2015 Gabi Melman.
|
|
// Distributed under the MIT License (http://opensource.org/licenses/MIT)
|
|
//
|
|
|
|
//
|
|
// bench.cpp : spdlog benchmarks
|
|
//
|
|
#include "spdlog/spdlog.h"
|
|
#include "spdlog/sinks/basic_file_sink.h"
|
|
#include "spdlog/sinks/daily_file_sink.h"
|
|
#include "spdlog/sinks/null_sink.h"
|
|
#include "spdlog/sinks/rotating_file_sink.h"
|
|
|
|
#include "utils.h"
|
|
#include <atomic>
|
|
#include <cstdlib> // EXIT_FAILURE
|
|
#include <memory>
|
|
#include <string>
|
|
#include <thread>
|
|
|
|
void bench(int howmany, std::shared_ptr<spdlog::logger> log);
|
|
void bench_mt(int howmany, std::shared_ptr<spdlog::logger> log, int thread_count);
|
|
|
|
// void bench_default_api(int howmany, std::shared_ptr<spdlog::logger> log);
|
|
// void bench_c_string(int howmany, std::shared_ptr<spdlog::logger> log);
|
|
|
|
static const size_t file_size = 30 * 1024 * 1024;
|
|
static const size_t rotating_files = 5;
|
|
static const int max_threads = 1000;
|
|
|
|
void bench_threaded_logging(int threads, int iters)
|
|
{
|
|
spdlog::info("**************************************************************");
|
|
spdlog::info("Multi threaded: {:n} threads, {:n} messages", threads, iters);
|
|
spdlog::info("**************************************************************");
|
|
|
|
auto basic_mt = spdlog::basic_logger_mt("basic_mt", "logs/basic_mt.log", true);
|
|
bench_mt(iters, std::move(basic_mt), threads);
|
|
auto basic_mt_tracing = spdlog::basic_logger_mt("basic_mt/backtrace-on", "logs/basic_mt.log", true);
|
|
basic_mt_tracing->enable_backtrace(32);
|
|
bench_mt(iters, std::move(basic_mt_tracing), threads);
|
|
|
|
spdlog::info("");
|
|
auto rotating_mt = spdlog::rotating_logger_mt("rotating_mt", "logs/rotating_mt.log", file_size, rotating_files);
|
|
bench_mt(iters, std::move(rotating_mt), threads);
|
|
auto rotating_mt_tracing = spdlog::rotating_logger_mt("rotating_mt/backtrace-on", "logs/rotating_mt.log", file_size, rotating_files);
|
|
rotating_mt_tracing->enable_backtrace(32);
|
|
bench_mt(iters, std::move(rotating_mt_tracing), threads);
|
|
|
|
spdlog::info("");
|
|
auto daily_mt = spdlog::daily_logger_mt("daily_mt", "logs/daily_mt.log");
|
|
bench_mt(iters, std::move(daily_mt), threads);
|
|
auto daily_mt_tracing = spdlog::daily_logger_mt("daily_mt/backtrace-on", "logs/daily_mt.log");
|
|
daily_mt_tracing->enable_backtrace(32);
|
|
bench_mt(iters, std::move(daily_mt_tracing), threads);
|
|
|
|
spdlog::info("");
|
|
auto empty_logger = std::make_shared<spdlog::logger>("level-off");
|
|
empty_logger->set_level(spdlog::level::off);
|
|
bench(iters, empty_logger);
|
|
auto empty_logger_tracing = std::make_shared<spdlog::logger>("level-off/backtrace-on");
|
|
empty_logger_tracing->set_level(spdlog::level::off);
|
|
empty_logger_tracing->enable_backtrace(32);
|
|
bench(iters, empty_logger_tracing);
|
|
}
|
|
|
|
void bench_single_threaded(int iters)
|
|
{
|
|
spdlog::info("**************************************************************");
|
|
spdlog::info("Single threaded: {:n} messages", iters);
|
|
spdlog::info("**************************************************************");
|
|
|
|
auto basic_st = spdlog::basic_logger_st("basic_st", "logs/basic_st.log", true);
|
|
bench(iters, std::move(basic_st));
|
|
|
|
auto basic_st_tracing = spdlog::basic_logger_st("basic_st/backtrace-on", "logs/basic_st.log", true);
|
|
bench(iters, std::move(basic_st_tracing));
|
|
|
|
spdlog::info("");
|
|
auto rotating_st = spdlog::rotating_logger_st("rotating_st", "logs/rotating_st.log", file_size, rotating_files);
|
|
bench(iters, std::move(rotating_st));
|
|
auto rotating_st_tracing = spdlog::rotating_logger_st("rotating_st/backtrace-on", "logs/rotating_st.log", file_size, rotating_files);
|
|
rotating_st_tracing->enable_backtrace(32);
|
|
bench(iters, std::move(rotating_st_tracing));
|
|
|
|
spdlog::info("");
|
|
auto daily_st = spdlog::daily_logger_st("daily_st", "logs/daily_st.log");
|
|
bench(iters, std::move(daily_st));
|
|
auto daily_st_tracing = spdlog::daily_logger_st("daily_st/backtrace-on", "logs/daily_st.log");
|
|
daily_st_tracing->enable_backtrace(32);
|
|
bench(iters, std::move(daily_st_tracing));
|
|
|
|
spdlog::info("");
|
|
auto empty_logger = std::make_shared<spdlog::logger>("level-off");
|
|
empty_logger->set_level(spdlog::level::off);
|
|
bench(iters, empty_logger);
|
|
|
|
auto empty_logger_tracing = std::make_shared<spdlog::logger>("level-off/backtrace-on");
|
|
empty_logger_tracing->set_level(spdlog::level::off);
|
|
empty_logger_tracing->enable_backtrace(32);
|
|
bench(iters, empty_logger_tracing);
|
|
}
|
|
|
|
int main(int argc, char *argv[])
|
|
{
|
|
spdlog::set_automatic_registration(false);
|
|
spdlog::default_logger()->set_pattern("[%^%l%$] %v");
|
|
int iters = 250000;
|
|
int threads = 4;
|
|
try
|
|
{
|
|
|
|
if (argc > 1)
|
|
{
|
|
iters = std::stoi(argv[1]);
|
|
}
|
|
if (argc > 2)
|
|
{
|
|
threads = std::stoi(argv[2]);
|
|
}
|
|
|
|
if (threads > max_threads)
|
|
{
|
|
throw std::runtime_error(fmt::format("Number of threads exceeds maximum({}})", max_threads));
|
|
}
|
|
|
|
bench_single_threaded(iters);
|
|
bench_threaded_logging(1, iters);
|
|
bench_threaded_logging(threads, iters);
|
|
}
|
|
catch (std::exception &ex)
|
|
{
|
|
spdlog::error(ex.what());
|
|
return EXIT_FAILURE;
|
|
}
|
|
return EXIT_SUCCESS;
|
|
}
|
|
|
|
void bench(int howmany, std::shared_ptr<spdlog::logger> log)
|
|
{
|
|
using std::chrono::duration;
|
|
using std::chrono::duration_cast;
|
|
using std::chrono::high_resolution_clock;
|
|
|
|
auto start = high_resolution_clock::now();
|
|
for (auto i = 0; i < howmany; ++i)
|
|
{
|
|
log->info("Hello logger: msg number {}", i);
|
|
}
|
|
|
|
auto delta = high_resolution_clock::now() - start;
|
|
auto delta_d = duration_cast<duration<double>>(delta).count();
|
|
|
|
spdlog::info("{:<30} Elapsed: {:0.2f} secs {:>16n}/sec", log->name(), delta_d, int(howmany / delta_d));
|
|
spdlog::drop(log->name());
|
|
}
|
|
|
|
void bench_mt(int howmany, std::shared_ptr<spdlog::logger> log, int thread_count)
|
|
{
|
|
using std::chrono::duration;
|
|
using std::chrono::duration_cast;
|
|
using std::chrono::high_resolution_clock;
|
|
|
|
std::vector<std::thread> threads;
|
|
threads.reserve(thread_count);
|
|
auto start = high_resolution_clock::now();
|
|
for (int t = 0; t < thread_count; ++t)
|
|
{
|
|
threads.emplace_back([&]() {
|
|
for (int j = 0; j < howmany / thread_count; j++)
|
|
{
|
|
log->info("Hello logger: msg number {}", j);
|
|
}
|
|
});
|
|
}
|
|
|
|
for (auto &t : threads)
|
|
{
|
|
t.join();
|
|
};
|
|
|
|
auto delta = high_resolution_clock::now() - start;
|
|
auto delta_d = duration_cast<duration<double>>(delta).count();
|
|
spdlog::info("{:<30} Elapsed: {:0.2f} secs {:>16n}/sec", log->name(), delta_d, int(howmany / delta_d));
|
|
spdlog::drop(log->name());
|
|
}
|
|
|
|
/*
|
|
void bench_default_api(int howmany, std::shared_ptr<spdlog::logger> log)
|
|
{
|
|
using std::chrono::high_resolution_clock;
|
|
using std::chrono::duration;
|
|
using std::chrono::duration_cast;
|
|
|
|
auto orig_default = spdlog::default_logger();
|
|
spdlog::set_default_logger(log);
|
|
auto start = high_resolution_clock::now();
|
|
for (auto i = 0; i < howmany; ++i)
|
|
{
|
|
spdlog::info("Hello logger: msg number {}", i);
|
|
}
|
|
|
|
auto delta = high_resolution_clock::now() - start;
|
|
auto delta_d = duration_cast<duration<double>>(delta).count();
|
|
spdlog::drop(log->name());
|
|
spdlog::set_default_logger(std::move(orig_default));
|
|
spdlog::info("{:<30} Elapsed: {:0.2f} secs {:>16n}/sec", log->name(), delta_d, int(howmany / delta_d));
|
|
}
|
|
|
|
void bench_c_string(int howmany, std::shared_ptr<spdlog::logger> log)
|
|
{
|
|
using std::chrono::high_resolution_clock;
|
|
using std::chrono::duration;
|
|
using std::chrono::duration_cast;
|
|
|
|
const char *msg = "Lorem ipsum dolor sit amet, consectetur adipiscing elit. Vestibulum pharetra metus cursus "
|
|
"lacus placerat congue. Nulla egestas, mauris a tincidunt tempus, enim lectus volutpat mi, eu consequat sem "
|
|
"libero nec massa. In dapibus ipsum a diam rhoncus gravida. Etiam non dapibus eros. Donec fringilla dui sed "
|
|
"augue pretium, nec scelerisque est maximus. Nullam convallis, sem nec blandit maximus, nisi turpis ornare "
|
|
"nisl, sit amet volutpat neque massa eu odio. Maecenas malesuada quam ex, posuere congue nibh turpis duis.";
|
|
|
|
auto orig_default = spdlog::default_logger();
|
|
spdlog::set_default_logger(log);
|
|
auto start = high_resolution_clock::now();
|
|
for (auto i = 0; i < howmany; ++i)
|
|
{
|
|
spdlog::log(spdlog::level::info, msg);
|
|
}
|
|
|
|
auto delta = high_resolution_clock::now() - start;
|
|
auto delta_d = duration_cast<duration<double>>(delta).count();
|
|
spdlog::drop(log->name());
|
|
spdlog::set_default_logger(std::move(orig_default));
|
|
spdlog::info("{:<30} Elapsed: {:0.2f} secs {:>16n}/sec", log->name(), delta_d, int(howmany / delta_d));
|
|
}
|
|
|
|
*/ |