henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 1 | /* |
| 2 | * Copyright 2004 The WebRTC Project Authors. All rights reserved. |
| 3 | * |
| 4 | * Use of this source code is governed by a BSD-style license |
| 5 | * that can be found in the LICENSE file in the root of the source |
| 6 | * tree. An additional intellectual property rights grant can be found |
| 7 | * in the file PATENTS. All contributing project authors may |
| 8 | * be found in the AUTHORS file in the root of the source tree. |
| 9 | */ |
| 10 | |
Mirko Bonadei | 92ea95e | 2017-09-15 04:47:31 | [diff] [blame] | 11 | #include "rtc_base/logging.h" |
Yves Gerey | 3e70781 | 2018-11-28 15:47:49 | [diff] [blame] | 12 | |
| 13 | #include <string.h> |
| 14 | #include <algorithm> |
| 15 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 16 | #include "rtc_base/arraysize.h" |
| 17 | #include "rtc_base/checks.h" |
| 18 | #include "rtc_base/event.h" |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 19 | #include "rtc_base/platform_thread.h" |
Mirko Bonadei | 92ea95e | 2017-09-15 04:47:31 | [diff] [blame] | 20 | #include "rtc_base/stream.h" |
Steve Anton | 10542f2 | 2019-01-11 17:11:00 | [diff] [blame] | 21 | #include "rtc_base/time_utils.h" |
Yves Gerey | 3e70781 | 2018-11-28 15:47:49 | [diff] [blame] | 22 | #include "test/gtest.h" |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 23 | |
| 24 | namespace rtc { |
| 25 | |
Niels Möller | ff3dd0c | 2018-06-20 08:27:53 | [diff] [blame] | 26 | namespace { |
| 27 | |
| 28 | class StringStream : public StreamInterface { |
| 29 | public: |
| 30 | explicit StringStream(std::string* str); |
| 31 | explicit StringStream(const std::string& str); |
| 32 | |
| 33 | StreamState GetState() const override; |
| 34 | StreamResult Read(void* buffer, |
| 35 | size_t buffer_len, |
| 36 | size_t* read, |
| 37 | int* error) override; |
| 38 | StreamResult Write(const void* data, |
| 39 | size_t data_len, |
| 40 | size_t* written, |
| 41 | int* error) override; |
| 42 | void Close() override; |
Niels Möller | ff3dd0c | 2018-06-20 08:27:53 | [diff] [blame] | 43 | |
| 44 | private: |
| 45 | std::string& str_; |
| 46 | size_t read_pos_; |
| 47 | bool read_only_; |
| 48 | }; |
| 49 | |
| 50 | StringStream::StringStream(std::string* str) |
| 51 | : str_(*str), read_pos_(0), read_only_(false) {} |
| 52 | |
| 53 | StringStream::StringStream(const std::string& str) |
| 54 | : str_(const_cast<std::string&>(str)), read_pos_(0), read_only_(true) {} |
| 55 | |
| 56 | StreamState StringStream::GetState() const { |
| 57 | return SS_OPEN; |
| 58 | } |
| 59 | |
| 60 | StreamResult StringStream::Read(void* buffer, |
| 61 | size_t buffer_len, |
| 62 | size_t* read, |
| 63 | int* error) { |
| 64 | size_t available = std::min(buffer_len, str_.size() - read_pos_); |
| 65 | if (!available) |
| 66 | return SR_EOS; |
| 67 | memcpy(buffer, str_.data() + read_pos_, available); |
| 68 | read_pos_ += available; |
| 69 | if (read) |
| 70 | *read = available; |
| 71 | return SR_SUCCESS; |
| 72 | } |
| 73 | |
| 74 | StreamResult StringStream::Write(const void* data, |
| 75 | size_t data_len, |
| 76 | size_t* written, |
| 77 | int* error) { |
| 78 | if (read_only_) { |
| 79 | if (error) { |
| 80 | *error = -1; |
| 81 | } |
| 82 | return SR_ERROR; |
| 83 | } |
| 84 | str_.append(static_cast<const char*>(data), |
| 85 | static_cast<const char*>(data) + data_len); |
| 86 | if (written) |
| 87 | *written = data_len; |
| 88 | return SR_SUCCESS; |
| 89 | } |
| 90 | |
| 91 | void StringStream::Close() {} |
| 92 | |
Niels Möller | ff3dd0c | 2018-06-20 08:27:53 | [diff] [blame] | 93 | } // namespace |
| 94 | |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 95 | template <typename Base> |
Yves Gerey | 665174f | 2018-06-19 13:03:05 | [diff] [blame] | 96 | class LogSinkImpl : public LogSink, public Base { |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 97 | public: |
| 98 | LogSinkImpl() {} |
| 99 | |
Yves Gerey | 665174f | 2018-06-19 13:03:05 | [diff] [blame] | 100 | template <typename P> |
Tommi | 00aac5a | 2015-05-25 09:25:59 | [diff] [blame] | 101 | explicit LogSinkImpl(P* p) : Base(p) {} |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 102 | |
| 103 | private: |
| 104 | void OnLogMessage(const std::string& message) override { |
Yves Gerey | 665174f | 2018-06-19 13:03:05 | [diff] [blame] | 105 | static_cast<Base*>(this)->WriteAll(message.data(), message.size(), nullptr, |
| 106 | nullptr); |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 107 | } |
| 108 | }; |
| 109 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 110 | class LogMessageForTesting : public LogMessage { |
| 111 | public: |
| 112 | LogMessageForTesting(const char* file, |
| 113 | int line, |
| 114 | LoggingSeverity sev, |
| 115 | LogErrorContext err_ctx = ERRCTX_NONE, |
| 116 | int err = 0) |
| 117 | : LogMessage(file, line, sev, err_ctx, err) {} |
| 118 | |
| 119 | const std::string& get_extra() const { return extra_; } |
Paulina Hensman | f1e3cb4 | 2018-06-20 12:07:05 | [diff] [blame] | 120 | #if defined(WEBRTC_ANDROID) |
| 121 | const char* get_tag() const { return tag_; } |
| 122 | #endif |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 123 | |
| 124 | // Returns the contents of the internal log stream. |
| 125 | // Note that parts of the stream won't (as is) be available until *after* the |
| 126 | // dtor of the parent class has run. So, as is, this only represents a |
| 127 | // partially built stream. |
| 128 | std::string GetPrintStream() { |
| 129 | RTC_DCHECK(!is_finished_); |
| 130 | is_finished_ = true; |
| 131 | FinishPrintStream(); |
Jonas Olsson | d8c5078 | 2018-09-07 09:21:28 | [diff] [blame] | 132 | return print_stream_.Release(); |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 133 | } |
| 134 | |
| 135 | private: |
| 136 | bool is_finished_ = false; |
| 137 | }; |
| 138 | |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 139 | // Test basic logging operation. We should get the INFO log but not the VERBOSE. |
| 140 | // We should restore the correct global state at the end. |
| 141 | TEST(LogTest, SingleStream) { |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 142 | int sev = LogMessage::GetLogToStream(nullptr); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 143 | |
| 144 | std::string str; |
Tommi | 00aac5a | 2015-05-25 09:25:59 | [diff] [blame] | 145 | LogSinkImpl<StringStream> stream(&str); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 146 | LogMessage::AddLogToStream(&stream, LS_INFO); |
| 147 | EXPECT_EQ(LS_INFO, LogMessage::GetLogToStream(&stream)); |
| 148 | |
Mirko Bonadei | 675513b | 2017-11-09 10:09:25 | [diff] [blame] | 149 | RTC_LOG(LS_INFO) << "INFO"; |
| 150 | RTC_LOG(LS_VERBOSE) << "VERBOSE"; |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 151 | EXPECT_NE(std::string::npos, str.find("INFO")); |
| 152 | EXPECT_EQ(std::string::npos, str.find("VERBOSE")); |
| 153 | |
Jonas Olsson | f2ce37c | 2018-09-12 13:32:47 | [diff] [blame] | 154 | int i = 1; |
| 155 | long l = 2l; |
| 156 | long long ll = 3ll; |
| 157 | |
| 158 | unsigned int u = 4u; |
| 159 | unsigned long ul = 5ul; |
| 160 | unsigned long long ull = 6ull; |
| 161 | |
| 162 | std::string s1 = "char*"; |
| 163 | std::string s2 = "std::string"; |
| 164 | std::string s3 = "absl::stringview"; |
Niels Möller | 65835be | 2019-02-04 18:23:58 | [diff] [blame] | 165 | const char* null_string = nullptr; |
Jonas Olsson | f2ce37c | 2018-09-12 13:32:47 | [diff] [blame] | 166 | void* p = reinterpret_cast<void*>(0xabcd); |
| 167 | |
| 168 | // Log all suported types(except doubles/floats) as a sanity-check. |
| 169 | RTC_LOG(LS_INFO) << "|" << i << "|" << l << "|" << ll << "|" << u << "|" << ul |
| 170 | << "|" << ull << "|" << s1.c_str() << "|" << s2 << "|" |
Niels Möller | 65835be | 2019-02-04 18:23:58 | [diff] [blame] | 171 | << absl::string_view(s3) << "|" << p << "|" << null_string |
| 172 | << "|"; |
Jonas Olsson | f2ce37c | 2018-09-12 13:32:47 | [diff] [blame] | 173 | |
| 174 | // Signed integers |
| 175 | EXPECT_NE(std::string::npos, str.find("|1|")); |
| 176 | EXPECT_NE(std::string::npos, str.find("|2|")); |
| 177 | EXPECT_NE(std::string::npos, str.find("|3|")); |
| 178 | |
| 179 | // Unsigned integers |
| 180 | EXPECT_NE(std::string::npos, str.find("|4|")); |
| 181 | EXPECT_NE(std::string::npos, str.find("|5|")); |
| 182 | EXPECT_NE(std::string::npos, str.find("|6|")); |
| 183 | |
| 184 | // Strings |
| 185 | EXPECT_NE(std::string::npos, str.find("|char*|")); |
| 186 | EXPECT_NE(std::string::npos, str.find("|std::string|")); |
| 187 | EXPECT_NE(std::string::npos, str.find("|absl::stringview|")); |
| 188 | |
| 189 | // void* |
| 190 | EXPECT_NE(std::string::npos, str.find("|abcd|")); |
| 191 | |
Niels Möller | 65835be | 2019-02-04 18:23:58 | [diff] [blame] | 192 | // null char* |
| 193 | EXPECT_NE(std::string::npos, str.find("|(null)|")); |
| 194 | |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 195 | LogMessage::RemoveLogToStream(&stream); |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 196 | EXPECT_EQ(LS_NONE, LogMessage::GetLogToStream(&stream)); |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 197 | EXPECT_EQ(sev, LogMessage::GetLogToStream(nullptr)); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 198 | } |
| 199 | |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 200 | #if GTEST_HAS_DEATH_TEST && !defined(WEBRTC_ANDROID) |
| 201 | TEST(LogTest, Checks) { |
| 202 | EXPECT_DEATH(FATAL() << "message", |
| 203 | "\n\n#\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 204 | "# Fatal error in: \\S+, line \\w+\n" |
| 205 | "# last system error: \\w+\n" |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 206 | "# Check failed: FATAL\\(\\)\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 207 | "# message"); |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 208 | |
| 209 | int a = 1, b = 2; |
| 210 | EXPECT_DEATH(RTC_CHECK_EQ(a, b) << 1 << 2u, |
| 211 | "\n\n#\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 212 | "# Fatal error in: \\S+, line \\w+\n" |
| 213 | "# last system error: \\w+\n" |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 214 | "# Check failed: a == b \\(1 vs. 2\\)\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 215 | "# 12"); |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 216 | RTC_CHECK_EQ(5, 5); |
| 217 | |
| 218 | RTC_CHECK(true) << "Shouldn't crash" << 1; |
| 219 | EXPECT_DEATH(RTC_CHECK(false) << "Hi there!", |
| 220 | "\n\n#\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 221 | "# Fatal error in: \\S+, line \\w+\n" |
| 222 | "# last system error: \\w+\n" |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 223 | "# Check failed: false\n" |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 224 | "# Hi there!"); |
Jonas Olsson | f8e5c11 | 2018-06-14 11:14:22 | [diff] [blame] | 225 | } |
| 226 | #endif |
Jonas Olsson | d000b0a | 2018-07-03 10:07:53 | [diff] [blame] | 227 | |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 228 | // Test using multiple log streams. The INFO stream should get the INFO message, |
| 229 | // the VERBOSE stream should get the INFO and the VERBOSE. |
| 230 | // We should restore the correct global state at the end. |
| 231 | TEST(LogTest, MultipleStreams) { |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 232 | int sev = LogMessage::GetLogToStream(nullptr); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 233 | |
| 234 | std::string str1, str2; |
Tommi | 00aac5a | 2015-05-25 09:25:59 | [diff] [blame] | 235 | LogSinkImpl<StringStream> stream1(&str1), stream2(&str2); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 236 | LogMessage::AddLogToStream(&stream1, LS_INFO); |
| 237 | LogMessage::AddLogToStream(&stream2, LS_VERBOSE); |
| 238 | EXPECT_EQ(LS_INFO, LogMessage::GetLogToStream(&stream1)); |
| 239 | EXPECT_EQ(LS_VERBOSE, LogMessage::GetLogToStream(&stream2)); |
| 240 | |
Mirko Bonadei | 675513b | 2017-11-09 10:09:25 | [diff] [blame] | 241 | RTC_LOG(LS_INFO) << "INFO"; |
| 242 | RTC_LOG(LS_VERBOSE) << "VERBOSE"; |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 243 | |
| 244 | EXPECT_NE(std::string::npos, str1.find("INFO")); |
| 245 | EXPECT_EQ(std::string::npos, str1.find("VERBOSE")); |
| 246 | EXPECT_NE(std::string::npos, str2.find("INFO")); |
| 247 | EXPECT_NE(std::string::npos, str2.find("VERBOSE")); |
| 248 | |
| 249 | LogMessage::RemoveLogToStream(&stream2); |
| 250 | LogMessage::RemoveLogToStream(&stream1); |
Tommi | 0eefb4d | 2015-05-23 07:54:07 | [diff] [blame] | 251 | EXPECT_EQ(LS_NONE, LogMessage::GetLogToStream(&stream2)); |
| 252 | EXPECT_EQ(LS_NONE, LogMessage::GetLogToStream(&stream1)); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 253 | |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 254 | EXPECT_EQ(sev, LogMessage::GetLogToStream(nullptr)); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 255 | } |
| 256 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 257 | class LogThread { |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 258 | public: |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 259 | LogThread() : thread_(&ThreadEntry, this, "LogThread") {} |
| 260 | ~LogThread() { thread_.Stop(); } |
tommi | e725159 | 2017-07-14 21:44:46 | [diff] [blame] | 261 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 262 | void Start() { thread_.Start(); } |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 263 | |
| 264 | private: |
Niels Möller | 25aefd3 | 2018-11-21 08:34:11 | [diff] [blame] | 265 | void Run() { RTC_LOG(LS_VERBOSE) << "RTC_LOG"; } |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 266 | |
| 267 | static void ThreadEntry(void* p) { static_cast<LogThread*>(p)->Run(); } |
| 268 | |
| 269 | PlatformThread thread_; |
Niels Möller | c572ff3 | 2018-11-07 07:43:50 | [diff] [blame] | 270 | Event event_; |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 271 | }; |
| 272 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 273 | // Ensure we don't crash when adding/removing streams while threads are going. |
| 274 | // We should restore the correct global state at the end. |
henrike@webrtc.org | c732a3e | 2014-10-09 22:08:15 | [diff] [blame] | 275 | TEST(LogTest, MultipleThreads) { |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 276 | int sev = LogMessage::GetLogToStream(nullptr); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 277 | |
| 278 | LogThread thread1, thread2, thread3; |
| 279 | thread1.Start(); |
| 280 | thread2.Start(); |
| 281 | thread3.Start(); |
| 282 | |
Niels Möller | ff3dd0c | 2018-06-20 08:27:53 | [diff] [blame] | 283 | std::string s1, s2, s3; |
| 284 | LogSinkImpl<StringStream> stream1(&s1), stream2(&s2), stream3(&s3); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 285 | for (int i = 0; i < 1000; ++i) { |
Niels Möller | 25aefd3 | 2018-11-21 08:34:11 | [diff] [blame] | 286 | LogMessage::AddLogToStream(&stream1, LS_WARNING); |
| 287 | LogMessage::AddLogToStream(&stream2, LS_INFO); |
| 288 | LogMessage::AddLogToStream(&stream3, LS_VERBOSE); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 289 | LogMessage::RemoveLogToStream(&stream1); |
| 290 | LogMessage::RemoveLogToStream(&stream2); |
| 291 | LogMessage::RemoveLogToStream(&stream3); |
| 292 | } |
| 293 | |
deadbeef | 37f5ecf | 2017-02-27 22:06:41 | [diff] [blame] | 294 | EXPECT_EQ(sev, LogMessage::GetLogToStream(nullptr)); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 295 | } |
| 296 | |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 297 | TEST(LogTest, WallClockStartTime) { |
Peter Boström | 0c4e06b | 2015-10-07 10:23:21 | [diff] [blame] | 298 | uint32_t time = LogMessage::WallClockStartTime(); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 299 | // Expect the time to be in a sensible range, e.g. > 2012-01-01. |
| 300 | EXPECT_GT(time, 1325376000u); |
| 301 | } |
| 302 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 303 | TEST(LogTest, CheckExtraErrorField) { |
| 304 | LogMessageForTesting log_msg("some/path/myfile.cc", 100, LS_WARNING, |
| 305 | ERRCTX_ERRNO, 0xD); |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 306 | log_msg.stream() << "This gets added at dtor time"; |
phoglund | bb73873 | 2016-07-15 10:57:12 | [diff] [blame] | 307 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 308 | const std::string& extra = log_msg.get_extra(); |
| 309 | const size_t length_to_check = arraysize("[0x12345678]") - 1; |
| 310 | ASSERT_GE(extra.length(), length_to_check); |
| 311 | EXPECT_EQ(std::string("[0x0000000D]"), extra.substr(0, length_to_check)); |
| 312 | } |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 313 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 314 | TEST(LogTest, CheckFilePathParsed) { |
| 315 | LogMessageForTesting log_msg("some/path/myfile.cc", 100, LS_INFO); |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 316 | log_msg.stream() << "<- Does this look right?"; |
| 317 | |
| 318 | const std::string stream = log_msg.GetPrintStream(); |
Paulina Hensman | f1e3cb4 | 2018-06-20 12:07:05 | [diff] [blame] | 319 | #if defined(WEBRTC_ANDROID) |
| 320 | const char* tag = log_msg.get_tag(); |
| 321 | EXPECT_NE(nullptr, strstr(tag, "myfile.cc")); |
| 322 | EXPECT_NE(std::string::npos, stream.find("100")); |
| 323 | #else |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 324 | EXPECT_NE(std::string::npos, stream.find("(myfile.cc:100)")); |
Paulina Hensman | f1e3cb4 | 2018-06-20 12:07:05 | [diff] [blame] | 325 | #endif |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 326 | } |
| 327 | |
Paulina Hensman | f1e3cb4 | 2018-06-20 12:07:05 | [diff] [blame] | 328 | #if defined(WEBRTC_ANDROID) |
| 329 | TEST(LogTest, CheckTagAddedToStringInDefaultOnLogMessageAndroid) { |
| 330 | std::string str; |
| 331 | LogSinkImpl<StringStream> stream(&str); |
| 332 | LogMessage::AddLogToStream(&stream, LS_INFO); |
| 333 | EXPECT_EQ(LS_INFO, LogMessage::GetLogToStream(&stream)); |
| 334 | |
| 335 | RTC_LOG_TAG(LS_INFO, "my_tag") << "INFO"; |
| 336 | EXPECT_NE(std::string::npos, str.find("INFO")); |
| 337 | EXPECT_NE(std::string::npos, str.find("my_tag")); |
| 338 | } |
| 339 | #endif |
| 340 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 341 | // Test the time required to write 1000 80-character logs to a string. |
| 342 | TEST(LogTest, Perf) { |
| 343 | std::string str; |
| 344 | LogSinkImpl<StringStream> stream(&str); |
Niels Möller | 25aefd3 | 2018-11-21 08:34:11 | [diff] [blame] | 345 | LogMessage::AddLogToStream(&stream, LS_VERBOSE); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 346 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 347 | const std::string message(80, 'X'); |
Niels Möller | 25aefd3 | 2018-11-21 08:34:11 | [diff] [blame] | 348 | { LogMessageForTesting sanity_check_msg(__FILE__, __LINE__, LS_VERBOSE); } |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 349 | |
| 350 | // We now know how many bytes the logging framework will tag onto every msg. |
| 351 | const size_t logging_overhead = str.size(); |
| 352 | // Reset the stream to 0 size. |
| 353 | str.clear(); |
| 354 | str.reserve(120000); |
| 355 | static const int kRepetitions = 1000; |
| 356 | |
Honghai Zhang | 82d7862 | 2016-05-06 18:29:15 | [diff] [blame] | 357 | int64_t start = TimeMillis(), finish; |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 358 | for (int i = 0; i < kRepetitions; ++i) { |
Niels Möller | 25aefd3 | 2018-11-21 08:34:11 | [diff] [blame] | 359 | LogMessageForTesting(__FILE__, __LINE__, LS_VERBOSE).stream() << message; |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 360 | } |
Honghai Zhang | 82d7862 | 2016-05-06 18:29:15 | [diff] [blame] | 361 | finish = TimeMillis(); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 362 | |
| 363 | LogMessage::RemoveLogToStream(&stream); |
| 364 | stream.Close(); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 365 | |
Tommi | fef0500 | 2018-02-27 12:51:08 | [diff] [blame] | 366 | EXPECT_EQ(str.size(), (message.size() + logging_overhead) * kRepetitions); |
| 367 | RTC_LOG(LS_INFO) << "Total log time: " << TimeDiff(finish, start) << " ms " |
| 368 | << " total bytes logged: " << str.size(); |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 369 | } |
| 370 | |
Amit Hilbuch | 10c5a93 | 2019-03-18 20:09:18 | [diff] [blame] | 371 | TEST(LogTest, EnumsAreSupported) { |
| 372 | enum class TestEnum { kValue0 = 0, kValue1 = 1 }; |
| 373 | std::string str; |
| 374 | LogSinkImpl<StringStream> stream(&str); |
| 375 | LogMessage::AddLogToStream(&stream, LS_INFO); |
| 376 | RTC_LOG(LS_INFO) << "[" << TestEnum::kValue0 << "]"; |
| 377 | EXPECT_NE(std::string::npos, str.find("[0]")); |
| 378 | EXPECT_EQ(std::string::npos, str.find("[1]")); |
| 379 | RTC_LOG(LS_INFO) << "[" << TestEnum::kValue1 << "]"; |
| 380 | EXPECT_NE(std::string::npos, str.find("[1]")); |
| 381 | LogMessage::RemoveLogToStream(&stream); |
| 382 | stream.Close(); |
| 383 | } |
| 384 | |
henrike@webrtc.org | f048872 | 2014-05-13 18:00:26 | [diff] [blame] | 385 | } // namespace rtc |