From 121a7dcedf05084d5d12254c0d294e2121e915fd Mon Sep 17 00:00:00 2001 From: gabime Date: Wed, 24 Oct 2018 22:31:50 +0300 Subject: [PATCH] Use google benchmark to test latency --- bench/Makefile | 4 +- bench/latency.cpp | 137 ++++++++++++---------------------------------- 2 files changed, 36 insertions(+), 105 deletions(-) diff --git a/bench/Makefile b/bench/Makefile index ae23d3aa..13c3982c 100644 --- a/bench/Makefile +++ b/bench/Makefile @@ -1,5 +1,5 @@ CXX ?= g++ -CXXFLAGS = -march=native -Wall -Wextra -pedantic -std=c++11 -pthread -I../include -fmax-errors=1 +CXXFLAGS = -march=native -Wall -Wextra -pedantic -std=c++11 -pthread -I../include -fmax-errors=1 CXX_RELEASE_FLAGS = -Ofast -flto -Wl,--no-as-needed @@ -16,7 +16,7 @@ async_bench: async_bench.cpp latency: latency.cpp - $(CXX) latency.cpp -o latency $(CXXFLAGS) $(CXX_RELEASE_FLAGS) + $(CXX) latency.cpp -o latency $(CXXFLAGS) $(CXX_RELEASE_FLAGS) -lbenchmark .PHONY: clean diff --git a/bench/latency.cpp b/bench/latency.cpp index a696276a..e2e34d1a 100644 --- a/bench/latency.cpp +++ b/bench/latency.cpp @@ -7,6 +7,8 @@ // latency.cpp : spdlog latency benchmarks // +#include "benchmark/benchmark.h" + #include "spdlog/spdlog.h" #include "spdlog/async.h" #include "spdlog/sinks/basic_file_sink.h" @@ -14,6 +16,7 @@ #include "spdlog/sinks/null_sink.h" #include "spdlog/sinks/rotating_file_sink.h" + #include "utils.h" #include #include @@ -31,123 +34,51 @@ using namespace utils; void bench(int howmany, std::shared_ptr log); void bench_mt(int howmany, std::shared_ptr log, int thread_count); -int main(int, char *[]) +void bench_c_string(benchmark::State& state, std::shared_ptr logger) { - std::srand(static_cast(std::time(nullptr))); // use current time as seed for random generator - int howmany = 1000000; - int queue_size = howmany + 2; - int threads = 10; - size_t file_size = 30 * 1024 * 1024; - size_t rotating_files = 5; + 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."; - try + + for (auto _ : state) { - - cout << "******************************************************************" - "*************\n"; - cout << "Single thread\n"; - cout << "******************************************************************" - "*************\n"; - - auto basic_st = spdlog::basic_logger_mt("basic_st", "logs/basic_st.log", true); - bench(howmany, basic_st); - - auto rotating_st = spdlog::rotating_logger_st("rotating_st", "logs/rotating_st.log", file_size, rotating_files); - bench(howmany, rotating_st); - - auto daily_st = spdlog::daily_logger_st("daily_st", "logs/daily_st.log"); - bench(howmany, daily_st); - - bench(howmany, spdlog::create("null_st")); - - cout << "\n****************************************************************" - "***************\n"; - cout << threads << " threads sharing same logger\n"; - cout << "******************************************************************" - "*************\n"; - - auto basic_mt = spdlog::basic_logger_mt("basic_mt", "logs/basic_mt.log", true); - bench_mt(howmany, basic_mt, threads); - - auto rotating_mt = spdlog::rotating_logger_mt("rotating_mt", "logs/rotating_mt.log", file_size, rotating_files); - bench_mt(howmany, rotating_mt, threads); - - auto daily_mt = spdlog::daily_logger_mt("daily_mt", "logs/daily_mt.log"); - bench_mt(howmany, daily_mt, threads); - bench(howmany, spdlog::create("null_mt")); - - cout << "\n****************************************************************" - "***************\n"; - cout << "async logging.. " << threads << " threads sharing same logger\n"; - cout << "******************************************************************" - "*************\n"; - - for (int i = 0; i < 3; ++i) - { - spdlog::init_thread_pool(static_cast(queue_size), 1); - auto as = spdlog::basic_logger_mt("async", "logs/basic_async.log", true); - bench_mt(howmany, as, threads); - spdlog::drop("async"); - } + logger->info(msg); } - catch (std::exception &ex) - { - std::cerr << "Error: " << ex.what() << std::endl; - perror("Last error"); - return EXIT_FAILURE; - } - return EXIT_SUCCESS; } -void bench(int howmany, std::shared_ptr log) +void bench_formatted_int(benchmark::State& state, std::shared_ptr logger) { - using namespace std::chrono; - using chrono::high_resolution_clock; - using chrono::milliseconds; - using chrono::nanoseconds; - cout << log->name() << "...\t\t" << flush; - nanoseconds total_nanos = nanoseconds::zero(); - for (auto i = 0; i < howmany; ++i) + int i = 0; + for (auto _ : state) { - auto start = high_resolution_clock::now(); - log->info("Hello logger: msg number {}", i); - auto delta_nanos = chrono::duration_cast(high_resolution_clock::now() - start); - total_nanos += delta_nanos; + logger->info("Hello message {} {} {}", ++i, i , i); } - - auto avg = total_nanos.count() / howmany; - cout << format(avg) << " ns/call" << endl; } -void bench_mt(int howmany, std::shared_ptr log, int thread_count) + + + +int main(int argc, char *argv[]) { - using namespace std::chrono; - using chrono::high_resolution_clock; - using chrono::milliseconds; - using chrono::nanoseconds; - cout << log->name() << "...\t\t" << flush; - vector threads; - std::atomic total_nanos{0}; - for (int t = 0; t < thread_count; ++t) - { - threads.push_back(std::thread([&]() { - for (int j = 0; j < howmany / thread_count; j++) - { - auto start = high_resolution_clock::now(); - log->info("Hello logger: msg number {}", j); - auto delta_nanos = chrono::duration_cast(high_resolution_clock::now() - start); - total_nanos += delta_nanos.count(); - } - })); - } + using spdlog::sinks::null_sink_st ; + using spdlog::sinks::null_sink_mt ; - for (auto &t : threads) - { - t.join(); - }; + auto null_logger = std::make_shared("bench", std::make_shared()); + benchmark::RegisterBenchmark("null_sink_st-500_bytes_cstr", bench_c_string, null_logger); + benchmark::RegisterBenchmark("null_sink_st-formatted_int", bench_formatted_int, null_logger); + + // 10 threads + int n_threads = 10; + null_logger = std::make_shared("bench", std::make_shared()); + benchmark::RegisterBenchmark("null_sink_mt-500_bytes_cstr", bench_c_string, null_logger) -> Threads(n_threads); + benchmark::RegisterBenchmark("null_sink_mt-formatted_int", bench_formatted_int, null_logger) ->Threads(n_threads); + + benchmark::Initialize(&argc, argv); + benchmark::RunSpecifiedBenchmarks(); - auto avg = total_nanos / howmany; - cout << format(avg) << " ns/call" << endl; }