folly/test/LoggingTest.cpp

   1 /*
   2  * Copyright 2014 Facebook, Inc.
   3  *
   4  * Licensed under the Apache License, Version 2.0 (the "License");
   5  * you may not use this file except in compliance with the License.
   6  * You may obtain a copy of the License at
   7  *
   8  *   http://www.apache.org/licenses/LICENSE-2.0
   9  *
  10  * Unless required by applicable law or agreed to in writing, software
  11  * distributed under the License is distributed on an "AS IS" BASIS,
  12  * WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied.
  13  * See the License for the specific language governing permissions and
  14  * limitations under the License.
  15  */
  16
  17 #include <folly/Logging.h>
  18 #include <gflags/gflags.h>
  19 #include <gtest/gtest.h>
  20 #include <folly/Benchmark.h>
  21 #include <vector>
  22
  23 TEST(LogEveryMs, basic) {
  24   std::vector<std::chrono::steady_clock::time_point> hist;
  25
  26   while (hist.size() < 10) {
  27     FB_LOG_EVERY_MS(INFO, 10)
  28       << "test msg " << (hist.push_back(std::chrono::steady_clock::now()),
  29                          hist.size());
  30   }
  31
  32   bool atLeastOneIsGood = false;
  33   for (int i = 0; i < hist.size() - 1; ++i) {
  34     auto delta = hist[i + 1] - hist[i];
  35     if (delta > std::chrono::milliseconds(5) &&
  36         delta < std::chrono::milliseconds(15)) {
  37       atLeastOneIsGood = true;
  38     }
  39   }
  40   EXPECT_TRUE(atLeastOneIsGood);
  41 }
  42
  43 BENCHMARK(skip_overhead, iter) {
  44   auto prev = FLAGS_minloglevel;
  45   FLAGS_minloglevel = 2;
  46
  47   for (unsigned i = 0; i < iter; ++i) {
  48     FB_LOG_EVERY_MS(INFO, 1000) << "every 1s";
  49   }
  50
  51   FLAGS_minloglevel = prev;
  52 }
  53
  54 BENCHMARK(dev_null_log_overhead, iter) {
  55   auto prev = FLAGS_minloglevel;
  56   FLAGS_minloglevel = 2;
  57
  58   for (unsigned i = 0; i < iter; ++i) {
  59     FB_LOG_EVERY_MS(INFO, -1) << "every -1ms";
  60   }
  61
  62   FLAGS_minloglevel = prev;
  63 }
  64
  65 // ============================================================================
  66 // folly/test/LoggingTest.cpp                      relative  time/iter  iters/s
  67 // ============================================================================
  68 // skip_overhead                                               36.37ns   27.49M
  69 // dev_null_log_overhead                                        2.61us  382.57K
  70 // ============================================================================
  71
  72 int main(int argc, char** argv) {
  73   testing::InitGoogleTest(&argc, argv);
  74   google::ParseCommandLineFlags(&argc, &argv, true);
  75
  76   auto rv = RUN_ALL_TESTS();
  77   if (!rv && FLAGS_benchmark) {
  78     folly::runBenchmarks();
  79   }
  80   return rv;
  81 }