• Home
  • Line#
  • Scopes#
  • Navigate#
  • Raw
  • Download
1 // Copyright (c) 2011 The Chromium Authors. All rights reserved.
2 // Use of this source code is governed by a BSD-style license that can be
3 // found in the LICENSE file.
4 
5 #ifndef _LOGGING_H_
6 #define _LOGGING_H_
7 
8 #include <errno.h>
9 #include <string.h>
10 #include <time.h>
11 #include <string>
12 #include <strstream>
13 #include <vector>
14 
15 #ifndef COMPILER_MSVC
16 #include <unistd.h>   // for _exit()
17 #endif
18 
19 #include "base/port.h"
20 #include "base/basictypes.h"
21 #include "base/commandlineflags.h"
22 #include "base/crash.h"
23 #include "base/dynamic_annotations.h"
24 #include "base/macros.h"
25 #include "base/memory/scoped_ptr.h"
26 #include "base/stl_decl_msvc.h"
27 #include "base/log_severity.h"
28 #include "base/vlog_is_on.h"
29 #include "global_strip_options.h"
30 
31 // Make a bunch of macros for logging.  The way to log things is to stream
32 // things to LOG(<a particular severity level>).  E.g.,
33 //
34 //   LOG(INFO) << "Found " << num_cookies << " cookies";
35 //
36 // You can capture log messages in a string, rather than reporting them
37 // immediately:
38 //
39 //   vector<string> errors;
40 //   LOG_STRING(ERROR, &errors) << "Couldn't parse cookie #" << cookie_num;
41 //
42 // This pushes back the new error onto 'errors'; if given a NULL pointer,
43 // it reports the error via LOG(ERROR).
44 //
45 // You can also do conditional logging:
46 //
47 //   LOG_IF(INFO, num_cookies > 10) << "Got lots of cookies";
48 //
49 // You can also do occasional logging (log every n'th occurrence of an
50 // event):
51 //
52 //   LOG_EVERY_N(INFO, 10) << "Got the " << COUNTER << "th cookie";
53 //
54 // The above will cause log messages to be output on the 1st, 11th, 21st, ...
55 // times it is executed.  Note that the special COUNTER value is used to
56 // identify which repetition is happening.
57 //
58 // You can also do occasional conditional logging (log every n'th
59 // occurrence of an event, when condition is satisfied):
60 //
61 //   LOG_IF_EVERY_N(INFO, (size > 1024), 10) << "Got the " << COUNTER
62 //                                           << "th big cookie";
63 //
64 // You can log messages the first N times your code executes a line. E.g.
65 //
66 //   LOG_FIRST_N(INFO, 20) << "Got the " << COUNTER << "th cookie";
67 //
68 // Outputs log messages for the first 20 times it is executed.
69 //
70 // Analogous SYSLOG, SYSLOG_IF, and SYSLOG_EVERY_N macros are available.
71 // These log to syslog as well as to the normal logs.  If you use these at
72 // all, you need to be aware that syslog can drastically reduce performance,
73 // especially if it is configured for remote logging!  Don't use these
74 // unless you fully understand this and have a concrete need to use them.
75 // Even then, try to minimize your use of them.
76 //
77 // There are also "debug mode" logging macros like the ones above:
78 //
79 //   DLOG(INFO) << "Found cookies";
80 //
81 //   DLOG_IF(INFO, num_cookies > 10) << "Got lots of cookies";
82 //
83 //   DLOG_EVERY_N(INFO, 10) << "Got the " << COUNTER << "th cookie";
84 //
85 // All "debug mode" logging is compiled away to nothing for non-debug mode
86 // compiles.
87 //
88 // We also have
89 //
90 //   LOG_ASSERT(assertion);
91 //   DLOG_ASSERT(assertion);
92 //
93 // which is syntactic sugar for {,D}LOG_IF(FATAL, assert fails) << assertion;
94 //
95 // We also override the standard 'assert' to use 'DLOG_ASSERT'.
96 //
97 // There are "verbose level" logging macros.  They look like
98 //
99 //   VLOG(1) << "I'm printed when you run the program with --v=1 or more";
100 //   VLOG(2) << "I'm printed when you run the program with --v=2 or more";
101 //
102 // These always log at the INFO log level (when they log at all).
103 // The verbose logging can also be turned on module-by-module.  For instance,
104 //    --vmodule=recordio=2,file=1,gfs*=3 --v=0
105 // will cause:
106 //   a. VLOG(2) and lower messages to be printed from recordio.{h,cc}
107 //   b. VLOG(1) and lower messages to be printed from google2file
108 //   c. VLOG(3) and lower messages to be printed from files prefixed with "gfs"
109 //   d. VLOG(0) and lower messages to be printed from elsewhere
110 //
111 // The wildcarding functionality shown by (c) supports both '*' (match
112 // 0 or more characters) and '?' (match any single character) wildcards.
113 //
114 // There's also VLOG_IS_ON(n) "verbose level" condition macro. To be used as
115 //
116 //   if (VLOG_IS_ON(2)) {
117 //     // do some logging preparation and logging
118 //     // that can't be accomplished with just VLOG(2) << ...;
119 //   }
120 //
121 // There are also VLOG_IF, VLOG_EVERY_N and VLOG_IF_EVERY_N "verbose level"
122 // condition macros for sample cases, when some extra computation and
123 // preparation for logs is not needed.
124 //   VLOG_IF(1, (size > 1024))
125 //      << "I'm printed when size is more than 1024 and when you run the "
126 //         "program with --v=1 or more";
127 //   VLOG_EVERY_N(1, 10)
128 //      << "I'm printed every 10th occurrence, and when you run the program "
129 //         "with --v=1 or more. Present occurence is " << COUNTER;
130 //   VLOG_IF_EVERY_N(1, (size > 1024), 10)
131 //      << "I'm printed on every 10th occurence of case when size is more "
132 //         " than 1024, when you run the program with --v=1 or more. ";
133 //         "Present occurence is " << COUNTER;
134 //
135 // [MLOG is OBSOLETE - use the more convenient VLOG(n) macros]
136 // There is also an MLOG option that enables module-level logging.  MLOG
137 // is associated with a specific flag by defining a MODULE_FLAG macro.
138 // Other than this, it behaves like VLOG.  Example:
139 //    DEFINE_int32(dnsverbose, 0, "Verbose level for DNS module");
140 //    #define MODULE_FLAG FLAGS_dnsverbose
141 //    MLOG(1) << "I'm printed when you run with --dnsverbose=1 or more";
142 //
143 // The supported severity levels for macros that allow you to specify one
144 // are (in increasing order of severity) INFO, WARNING, ERROR, and FATAL.
145 // Note that messages of a given severity are logged not only in the
146 // logfile for that severity, but also in all logfiles of lower severity.
147 // E.g., a message of severity FATAL will be logged to the logfiles of
148 // severity FATAL, ERROR, WARNING, and INFO.
149 //
150 // There is also the special severity of DFATAL, which logs FATAL in
151 // debug mode, ERROR in normal mode.
152 //
153 // Very important: logging a message at the FATAL severity level causes
154 // the program to terminate (after the message is logged).
155 //
156 // Unless otherwise specified, logs will be written to the filename
157 // "<program name>.<hostname>.<user name>.log.<severity level>.", followed
158 // by the date, time, and pid (you can't prevent the date, time, and pid
159 // from being in the filename).
160 //
161 // The logging code takes two flags:
162 //     --v=#           set the verbose level
163 //     --logtostderr   log all the messages to stderr instead of to logfiles
164 
165 // LOG LINE PREFIX FORMAT
166 //
167 // Log lines have this form:
168 //
169 //     Lmmdd hh:mm:ss.uuuuuu threadid file:line] msg...
170 //
171 // where the fields are defined as follows:
172 //
173 //   L                A single character, representing the log level
174 //                    (eg 'I' for INFO)
175 //   mm               The month (zero padded; ie May is '05')
176 //   dd               The day (zero padded)
177 //   hh:mm:ss.uuuuuu  Time in hours, minutes and fractional seconds
178 //   threadid         The space-padded thread ID as returned by GetTID()
179 //                    (this matches the PID on Linux)
180 //   file             The file name
181 //   line             The line number
182 //   msg              The user-supplied message
183 //
184 // Example:
185 //
186 //   I1103 11:57:31.739339 24395 google.cc:2341] Command line: ./some_prog
187 //   I1103 11:57:31.739403 24395 google.cc:2342] Process id 24395
188 //
189 // NOTE: although the microseconds are useful for comparing events on
190 // a single machine, clocks on different machines may not be well
191 // synchronized.  Hence, use caution when comparing the low bits of
192 // timestamps from different machines.
193 
194 // Set whether log messages go to stderr instead of logfiles
195 DECLARE_bool(logtostderr);
196 
197 // Set whether log messages go to stderr in addition to logfiles.
198 DECLARE_bool(alsologtostderr);
199 
200 // Log messages at a level >= this flag are automatically sent to
201 // stderr in addition to log files.
202 DECLARE_int32(stderrthreshold);
203 
204 // Set whether the log prefix should be prepended to each line of output.
205 DECLARE_bool(log_prefix);
206 
207 // Log messages at a level <= this flag are buffered.
208 // Log messages at a higher level are flushed immediately.
209 DECLARE_int32(logbuflevel);
210 
211 // Sets the maximum number of seconds which logs may be buffered for.
212 DECLARE_int32(logbufsecs);
213 
214 // Should Google1 logging be turned on?
215 DECLARE_bool(logging);
216 
217 // Log suppression level: messages logged at a lower level than this
218 // are suppressed.
219 DECLARE_int32(minloglevel);
220 
221 // If specified, logfiles are written into this directory instead of the
222 // default logging directory.
223 DECLARE_string(log_dir);
224 
225 // Sets the path of the directory into which to put additional links
226 // to the log files.
227 DECLARE_string(log_link);
228 
229 // Sets the maximum log file size (in MB).
230 DECLARE_int32(max_log_size);
231 
232 // Should log IO be directed to a background thread?  This flag has no
233 // effect unless //thread/logger:logger is linked into the binary.
234 DECLARE_bool(threaded_logging);
235 
236 // Set to cause StatusMessage() to write status to ./STATUS file.
237 DECLARE_bool(status_messages_to_status_file);
238 
239 // Sets whether to avoid logging to the disk if the disk is full.
240 DECLARE_bool(stop_logging_if_full_disk);
241 
242 // Log messages below the STRIP_LOG level will be compiled away for
243 // security reasons. See LOG(severtiy) below. STRIP_LOG is defined in
244 // //base/global_strip_log.h
245 
246 // A few definitions of macros that don't generate much code.  Since
247 // LOG(INFO) and its ilk are used all over our code, it's
248 // better to have compact code for these operations.
249 
250 #if STRIP_LOG == 0
251 #define COMPACT_GOOGLE_LOG_INFO LogMessage(__FILE__, __LINE__)
252 #define LOG_TO_STRING_INFO(message) LogMessage(__FILE__, __LINE__, INFO, \
253                                                message)
254 #else
255 #define COMPACT_GOOGLE_LOG_INFO NullStream()
256 #define LOG_TO_STRING_INFO(message) NullStream()
257 #endif
258 
259 #if STRIP_LOG <= 1
260 #define COMPACT_GOOGLE_LOG_WARNING LogMessage(__FILE__, __LINE__, WARNING)
261 #define LOG_TO_STRING_WARNING(message) LogMessage(__FILE__, __LINE__, \
262                                                   WARNING, message)
263 #else
264 #define COMPACT_GOOGLE_LOG_WARNING NullStream()
265 #define LOG_TO_STRING_WARNING(message) NullStream()
266 #endif
267 
268 #if STRIP_LOG <= 2
269 #define COMPACT_GOOGLE_LOG_ERROR LogMessage(__FILE__, __LINE__, ERROR)
270 #define LOG_TO_STRING_ERROR(message) LogMessage(__FILE__, __LINE__, ERROR, \
271                                                 message)
272 #else
273 #define COMPACT_GOOGLE_LOG_ERROR NullStream()
274 #define LOG_TO_STRING_ERROR(message) NullStream()
275 #endif
276 
277 #if STRIP_LOG <= 3
278 #define COMPACT_GOOGLE_LOG_FATAL LogMessageFatal(__FILE__, __LINE__)
279 #define COMPACT_GOOGLE_LOG_QFATAL LogMessageQuietlyFatal(__FILE__, __LINE__)
280 #define LOG_TO_STRING_FATAL(message) LogMessage(__FILE__, __LINE__, FATAL, \
281                                                 message)
282 #else
283 #define COMPACT_GOOGLE_LOG_FATAL NullStreamFatal()
284 #define COMPACT_GOOGLE_LOG_QFATAL NullStreamFatal()
285 #define LOG_TO_STRING_FATAL(message) NullStreamFatal()
286 #endif
287 
288 // For DFATAL, we want to use LogMessage (as opposed to
289 // LogMessageFatal), to be consistent with the original behavior.
290 #ifdef NDEBUG
291 #define COMPACT_GOOGLE_LOG_DFATAL COMPACT_GOOGLE_LOG_ERROR
292 #elif STRIP_LOG <= 3
293 #define COMPACT_GOOGLE_LOG_DFATAL LogMessage(__FILE__, __LINE__, FATAL)
294 #else
295 #define COMPACT_GOOGLE_LOG_DFATAL NullStreamFatal()
296 #endif
297 
298 #define GOOGLE_LOG_INFO(counter) \
299   LogMessage(__FILE__, __LINE__, INFO, counter, &LogMessage::SendToLog)
300 #define SYSLOG_INFO(counter) \
301   LogMessage(__FILE__, __LINE__, INFO, counter, \
302   &LogMessage::SendToSyslogAndLog)
303 #define GOOGLE_LOG_WARNING(counter)  \
304   LogMessage(__FILE__, __LINE__, WARNING, counter, &LogMessage::SendToLog)
305 #define SYSLOG_WARNING(counter)  \
306   LogMessage(__FILE__, __LINE__, WARNING, counter, \
307   &LogMessage::SendToSyslogAndLog)
308 #define GOOGLE_LOG_ERROR(counter)  \
309   LogMessage(__FILE__, __LINE__, ERROR, counter, &LogMessage::SendToLog)
310 #define SYSLOG_ERROR(counter)  \
311   LogMessage(__FILE__, __LINE__, ERROR, counter, \
312   &LogMessage::SendToSyslogAndLog)
313 #define GOOGLE_LOG_FATAL(counter) \
314   LogMessage(__FILE__, __LINE__, FATAL, counter, &LogMessage::SendToLog)
315 #define SYSLOG_FATAL(counter) \
316   LogMessage(__FILE__, __LINE__, FATAL, counter, \
317   &LogMessage::SendToSyslogAndLog)
318 #define GOOGLE_LOG_DFATAL(counter) \
319   LogMessage(__FILE__, __LINE__, DFATAL_LEVEL, counter, &LogMessage::SendToLog)
320 #define SYSLOG_DFATAL(counter) \
321   LogMessage(__FILE__, __LINE__, DFATAL_LEVEL, counter, \
322   &LogMessage::SendToSyslogAndLog)
323 
324 #ifdef OS_WINDOWS
325 // A very useful logging macro to log windows errors:
326 #define LOG_SYSRESULT(result) \
327   if (FAILED(result)) { \
328     LPTSTR message = NULL; \
329     LPTSTR msg = reinterpret_cast<LPTSTR>(&message); \
330     DWORD message_length = FormatMessage(FORMAT_MESSAGE_ALLOCATE_BUFFER | \
331                          FORMAT_MESSAGE_FROM_SYSTEM, \
332                          0, result, 0, msg, 100, NULL); \
333     if (message_length > 0) { \
334       LogMessage(__FILE__, __LINE__, ERROR, 0, \
335                  &LogMessage::SendToLog).stream() << message; \
336       LocalFree(message); \
337     } \
338   }
339 #endif
340 
341 // We use the preprocessor's merging operator, "##", so that, e.g.,
342 // LOG(INFO) becomes the token GOOGLE_LOG_INFO.  There's some funny
343 // subtle difference between ostream member streaming functions (e.g.,
344 // ostream::operator<<(int) and ostream non-member streaming functions
345 // (e.g., ::operator<<(ostream&, string&): it turns out that it's
346 // impossible to stream something like a string directly to an unnamed
347 // ostream. We employ a neat hack by calling the stream() member
348 // function of LogMessage which seems to avoid the problem.
349 #define LOG(severity) COMPACT_GOOGLE_LOG_ ## severity.stream()
350 #define SYSLOG(severity) SYSLOG_ ## severity(0).stream()
351 
352 // A convenient shorthand
353 #define LG LOG(INFO)
354 
355 class LogSink;  // defined below
356 
357 // If a non-NULL sink pointer is given, we push this message to that sink.
358 // For LOG_TO_SINK we then do normal LOG(severity) logging as well.
359 // This is useful for capturing messages and passing/storing them
360 // somewhere more specific than the global log of the process.
361 // Argument types:
362 //   LogSink* sink;
363 //   LogSeverity severity;
364 // The cast is to disambiguate NULL arguments.
365 #define LOG_TO_SINK(sink, severity) \
366   LogMessage(__FILE__, __LINE__, severity, \
367              static_cast<LogSink*>(sink), true).stream()
368 #define LOG_TO_SINK_BUT_NOT_TO_LOGFILE(sink, severity) \
369   LogMessage(__FILE__, __LINE__, severity, \
370              static_cast<LogSink*>(sink), false).stream()
371 
372 // If a non-NULL string pointer is given, we write this message to that string.
373 // We then do normal LOG(severity) logging as well.
374 // This is useful for capturing messages and storing them somewhere more
375 // specific than the global log of the process.
376 // Argument types:
377 //   string* message;
378 //   LogSeverity severity;
379 // The cast is to disambiguate NULL arguments.
380 // NOTE: LOG(severity) expands to LogMessage().stream() for the specified
381 // severity.
382 #define LOG_TO_STRING(severity, message) \
383   LOG_TO_STRING_##severity(static_cast<string*>(message)).stream()
384 
385 // If a non-NULL pointer is given, we push the message onto the end
386 // of a vector of strings; otherwise, we report it with LOG(severity).
387 // This is handy for capturing messages and perhaps passing them back
388 // to the caller, rather than reporting them immediately.
389 // Argument types:
390 //   LogSeverity severity;
391 //   vector<string> *outvec;
392 // The cast is to disambiguate NULL arguments.
393 #define LOG_STRING(severity, outvec) \
394   LOG_TO_STRING_##severity(static_cast<vector<string>*>(outvec)).stream()
395 
396 #define LOG_IF(severity, condition) \
397   !(condition) ? (void) 0 : LogMessageVoidify() & LOG(severity)
398 #define SYSLOG_IF(severity, condition) \
399   !(condition) ? (void) 0 : LogMessageVoidify() & SYSLOG(severity)
400 
401 #define LOG_ASSERT(condition)  \
402   LOG_IF(FATAL, !(condition)) << "Assert failed: " #condition
403 #define SYSLOG_ASSERT(condition) \
404   SYSLOG_IF(FATAL, !(condition)) << "Assert failed: " #condition
405 
406 // CHECK dies with a fatal error if condition is not true.  It is *not*
407 // controlled by NDEBUG, so the check will be executed regardless of
408 // compilation mode.  Therefore, it is safe to do things like:
409 //    CHECK(fp->Write(x) == 4)
410 #define CHECK(condition)  \
411       LOG_IF(FATAL, PREDICT_FALSE(!(condition))) \
412              << "Check failed: " #condition " "
413 
414 // QCHECK is a quiet version of CHECK. It has all of the same properties,
415 // except that when it dies it simply prints out this message and doesn't
416 // dump a giant stack trace, etc. This is good for tests like sanity-checking
417 // user inputs, where your own failure message is really the only thing you
418 // need or want to display.
419 #define QCHECK(condition)  \
420       LOG_IF(QFATAL, PREDICT_FALSE(!(condition))) \
421              << "Check failed: " #condition " "
422 
423 // A container for a string pointer which can be evaluated to a bool -
424 // true iff the pointer is NULL.
425 struct CheckOpString {
CheckOpStringCheckOpString426   CheckOpString(string* str) : str_(str) { }
427   // No destructor: if str_ is non-NULL, we're about to LOG(FATAL),
428   // so there's no point in cleaning up str_.
429   operator bool() const { return PREDICT_FALSE(str_ != NULL); }
430   string* str_;
431 };
432 
433 // Function is overloaded for integral types to allow static const
434 // integrals declared in classes and not defined to be used as arguments to
435 // CHECK* macros. It's not encouraged though.
436 template <class T>
GetReferenceableValue(const T & t)437 inline const T&       GetReferenceableValue(const T&           t) { return t; }
GetReferenceableValue(char t)438 inline char           GetReferenceableValue(char               t) { return t; }
GetReferenceableValue(unsigned char t)439 inline unsigned char  GetReferenceableValue(unsigned char      t) { return t; }
GetReferenceableValue(signed char t)440 inline signed char    GetReferenceableValue(signed char        t) { return t; }
GetReferenceableValue(short t)441 inline short          GetReferenceableValue(short              t) { return t; }
GetReferenceableValue(unsigned short t)442 inline unsigned short GetReferenceableValue(unsigned short     t) { return t; }
GetReferenceableValue(int t)443 inline int            GetReferenceableValue(int                t) { return t; }
GetReferenceableValue(unsigned int t)444 inline unsigned int   GetReferenceableValue(unsigned int       t) { return t; }
GetReferenceableValue(long t)445 inline long           GetReferenceableValue(long               t) { return t; }
GetReferenceableValue(unsigned long t)446 inline unsigned long  GetReferenceableValue(unsigned long      t) { return t; }
GetReferenceableValue(long long t)447 inline long long      GetReferenceableValue(long long          t) { return t; }
GetReferenceableValue(unsigned long long t)448 inline unsigned long long GetReferenceableValue(unsigned long long t) {
449   return t;
450 }
451 
452 // Build the error message string.
453 template<class t1, class t2>
MakeCheckOpString(const t1 & v1,const t2 & v2,const char * names)454 string* MakeCheckOpString(const t1& v1, const t2& v2, const char* names) {
455   strstream ss;
456   ss << names << " (" << v1 << " vs. " << v2 << ")";
457   return new string(ss.str(), ss.pcount());
458 }
459 
460 // Helper functions for CHECK_OP macro.
461 // The (int, int) specialization works around the issue that the compiler
462 // will not instantiate the template version of the function on values of
463 // unnamed enum type - see comment below.
464 #define DEFINE_CHECK_OP_IMPL(name, op) \
465   template <class t1, class t2> \
466   inline string* Check##name##Impl(const t1& v1, const t2& v2, \
467                                    const char* names) { \
468     if (v1 op v2) return NULL; \
469     else return MakeCheckOpString(v1, v2, names); \
470   } \
471   inline string* Check##name##Impl(int v1, int v2, const char* names) { \
472     return Check##name##Impl<int, int>(v1, v2, names); \
473   }
474 
475 // Use _EQ, _NE, _LE, etc. in case the file including base/logging.h
476 // provides its own #defines for the simpler names EQ, NE, LE, etc.
477 // This happens if, for example, those are used as token names in a
478 // yacc grammar.
479 DEFINE_CHECK_OP_IMPL(_EQ, ==)
480 DEFINE_CHECK_OP_IMPL(_NE, !=)
481 DEFINE_CHECK_OP_IMPL(_LE, <=)
482 DEFINE_CHECK_OP_IMPL(_LT, < )
483 DEFINE_CHECK_OP_IMPL(_GE, >=)
484 DEFINE_CHECK_OP_IMPL(_GT, > )
485 #undef DEFINE_CHECK_OP_IMPL
486 
487 // Helper macro for binary operators.
488 // Don't use this macro directly in your code, use CHECK_EQ et al below.
489 
490 #if defined(STATIC_ANALYSIS)
491 // Only for static analysis tool to know that it is equivalent to assert
492 #define CHECK_OP_LOG(name, op, val1, val2, log) CHECK((val1) op (val2))
493 #elif !defined(NDEBUG)
494 // In debug mode, avoid constructing CheckOpStrings if possible,
495 // to reduce the overhead of CHECK statments by 2x.
496 // Real DCHECK-heavy tests have seen 1.5x speedups.
497 
498 // The meaning of "string" might be different between now and
499 // when this macro gets invoked (e.g., if someone is experimenting
500 // with other string implementations that get defined after this
501 // file is included).  Save the current meaning now and use it
502 // in the macro.
503 typedef string _Check_string;
504 #define CHECK_OP_LOG(name, op, val1, val2, log) \
505   while (_Check_string* _result = \
506          Check##name##Impl(GetReferenceableValue(val1), \
507                            GetReferenceableValue(val2), \
508                            #val1 " " #op " " #val2)) \
509     log(__FILE__, __LINE__, CheckOpString(_result)).stream()
510 #else
511 // In optimized mode, use CheckOpString to hint to compiler that
512 // the while condition is unlikely.
513 #define CHECK_OP_LOG(name, op, val1, val2, log) \
514   while (CheckOpString _result = \
515          Check##name##Impl(GetReferenceableValue(val1), \
516                            GetReferenceableValue(val2), \
517                            #val1 " " #op " " #val2)) \
518     log(__FILE__, __LINE__, _result).stream()
519 #endif  // STATIC_ANALYSIS, !NDEBUG
520 
521 #if STRIP_LOG <= 3
522 #define CHECK_OP(name, op, val1, val2) \
523   CHECK_OP_LOG(name, op, val1, val2, LogMessageFatal)
524 #else
525 #define CHECK_OP(name, op, val1, val2) \
526   CHECK_OP_LOG(name, op, val1, val2, NullStreamFatal)
527 #endif // STRIP_LOG <= 3
528 #define QCHECK_OP(name, op, val1, val2) \
529   CHECK_OP_LOG(name, op, val1, val2, LogMessageQuietlyFatal)
530 
531 // Equality/Inequality checks - compare two values, and log a FATAL message
532 // including the two values when the result is not as expected.  The values
533 // must have operator<<(ostream, ...) defined.
534 //
535 // You may append to the error message like so:
536 //   CHECK_NE(1, 2) << ": The world must be ending!";
537 //
538 // We are very careful to ensure that each argument is evaluated exactly
539 // once, and that anything which is legal to pass as a function argument is
540 // legal here.  In particular, the arguments may be temporary expressions
541 // which will end up being destroyed at the end of the apparent statement,
542 // for example:
543 //   CHECK_EQ(string("abc")[1], 'b');
544 //
545 // WARNING: These don't compile correctly if one of the arguments is a pointer
546 // and the other is NULL. To work around this, simply static_cast NULL to the
547 // type of the desired pointer.
548 
549 #define CHECK_EQ(val1, val2) CHECK_OP(_EQ, ==, val1, val2)
550 #define CHECK_NE(val1, val2) CHECK_OP(_NE, !=, val1, val2)
551 #define CHECK_LE(val1, val2) CHECK_OP(_LE, <=, val1, val2)
552 #define CHECK_LT(val1, val2) CHECK_OP(_LT, < , val1, val2)
553 #define CHECK_GE(val1, val2) CHECK_OP(_GE, >=, val1, val2)
554 #define CHECK_GT(val1, val2) CHECK_OP(_GT, > , val1, val2)
555 
556 #define QCHECK_EQ(val1, val2) QCHECK_OP(_EQ, ==, val1, val2)
557 #define QCHECK_NE(val1, val2) QCHECK_OP(_NE, !=, val1, val2)
558 #define QCHECK_LE(val1, val2) QCHECK_OP(_LE, <=, val1, val2)
559 #define QCHECK_LT(val1, val2) QCHECK_OP(_LT, < , val1, val2)
560 #define QCHECK_GE(val1, val2) QCHECK_OP(_GE, >=, val1, val2)
561 #define QCHECK_GT(val1, val2) QCHECK_OP(_GT, > , val1, val2)
562 
563 
564 // Check that the input is non NULL.  This very useful in constructor
565 // initializer lists.
566 
567 #define CHECK_NOTNULL(val) \
568   CheckNotNull(__FILE__, __LINE__, "'" #val "' Must be non NULL", (val))
569 
570 // Helper functions for string comparisons.
571 // To avoid bloat, the definitions are in logging.cc.
572 #define DECLARE_CHECK_STROP_IMPL(func, expected) \
573   string* Check##func##expected##Impl(const char* s1, const char* s2, \
574                                       const char* names);
575 DECLARE_CHECK_STROP_IMPL(strcmp, true)
576 DECLARE_CHECK_STROP_IMPL(strcmp, false)
577 DECLARE_CHECK_STROP_IMPL(strcasecmp, true)
578 DECLARE_CHECK_STROP_IMPL(strcasecmp, false)
579 #undef DECLARE_CHECK_STROP_IMPL
580 
581 // Helper macro for string comparisons.
582 // Don't use this macro directly in your code, use CHECK_STREQ et al below.
583 #define CHECK_STROP(func, op, expected, s1, s2) \
584   while (CheckOpString _result = \
585          Check##func##expected##Impl((s1), (s2), \
586                                      #s1 " " #op " " #s2)) \
587     LOG(FATAL) << *_result.str_
588 #define QCHECK_STROP(func, op, expected, s1, s2) \
589   while (CheckOpString _result = \
590          Check##func##expected##Impl((s1), (s2), \
591                                      #s1 " " #op " " #s2)) \
592     LOG(QFATAL) << *_result.str_
593 
594 
595 // String (char*) equality/inequality checks.
596 // CASE versions are case-insensitive.
597 //
598 // Note that "s1" and "s2" may be temporary strings which are destroyed
599 // by the compiler at the end of the current "full expression"
600 // (e.g. CHECK_STREQ(Foo().c_str(), Bar().c_str())).
601 
602 #define CHECK_STREQ(s1, s2) CHECK_STROP(strcmp, ==, true, s1, s2)
603 #define CHECK_STRNE(s1, s2) CHECK_STROP(strcmp, !=, false, s1, s2)
604 #define CHECK_STRCASEEQ(s1, s2) CHECK_STROP(strcasecmp, ==, true, s1, s2)
605 #define CHECK_STRCASENE(s1, s2) CHECK_STROP(strcasecmp, !=, false, s1, s2)
606 
607 #define CHECK_INDEX(I,A) CHECK(I < (sizeof(A)/sizeof(A[0])))
608 #define CHECK_BOUND(B,A) CHECK(B <= (sizeof(A)/sizeof(A[0])))
609 
610 #define QCHECK_STREQ(s1, s2) QCHECK_STROP(strcmp, ==, true, s1, s2)
611 #define QCHECK_STRNE(s1, s2) QCHECK_STROP(strcmp, !=, false, s1, s2)
612 #define QCHECK_STRCASEEQ(s1, s2) QCHECK_STROP(strcasecmp, ==, true, s1, s2)
613 #define QCHECK_STRCASENE(s1, s2) QCHECK_STROP(strcasecmp, !=, false, s1, s2)
614 
615 #define QCHECK_INDEX(I,A) QCHECK(I < (sizeof(A)/sizeof(A[0])))
616 #define QCHECK_BOUND(B,A) QCHECK(B <= (sizeof(A)/sizeof(A[0])))
617 
618 // Likely to be deprecated; instead use
619 //   CHECK(MathUtil::NearByMargin(x, y))
620 // (or another similar function from util/math/mathutil.h).
621 #define CHECK_DOUBLE_EQ(val1, val2)              \
622   do {                                           \
623     CHECK_LE((val1), (val2)+0.000000000000001L); \
624     CHECK_GE((val1), (val2)-0.000000000000001L); \
625   } while (0)
626 
627 // Likely to be deprecated; instead use
628 //   CHECK(MathUtil::WithinMargin(x, y, margin))
629 // (or another similar function from util/math/mathutil.h).
630 #define CHECK_NEAR(val1, val2, margin)           \
631   do {                                           \
632     CHECK_LE((val1), (val2)+(margin));           \
633     CHECK_GE((val1), (val2)-(margin));           \
634   } while (0)
635 
636 // perror()..googly style!
637 //
638 // PLOG() and PLOG_IF() and PCHECK() behave exactly like their LOG* and
639 // CHECK equivalents with the addition that they postpend a description
640 // of the current state of errno to their output lines.
641 
642 #define PLOG(severity) GOOGLE_PLOG(severity, 0).stream()
643 
644 #define GOOGLE_PLOG(severity, counter)  \
645   ErrnoLogMessage(__FILE__, __LINE__, severity, counter, \
646                   &LogMessage::SendToLog)
647 
648 #define PLOG_IF(severity, condition) \
649   !(condition) ? (void) 0 : LogMessageVoidify() & PLOG(severity)
650 
651 // A CHECK() macro that postpends errno if the condition is false. E.g.
652 //
653 // if (poll(fds, nfds, timeout) == -1) { PCHECK(errno == EINTR); ... }
654 #define PCHECK(condition)  \
655       PLOG_IF(FATAL, PREDICT_FALSE(!(condition))) \
656               << "Check failed: " #condition " "
657 
658 // A CHECK() macro that lets you assert the success of a function that
659 // returns -1 and sets errno in case of an error. E.g.
660 //
661 // CHECK_ERR(mkdir(path, 0700));
662 //
663 // or
664 //
665 // int fd = open(filename, flags); CHECK_ERR(fd) << ": open " << filename;
666 #define CHECK_ERR(invocation)                                          \
667 PLOG_IF(FATAL, PREDICT_FALSE((invocation) == -1)) << #invocation
668 
669 // Use macro expansion to create, for each use of LOG_EVERY_N(), static
670 // variables with the __LINE__ expansion as part of the variable name.
671 #define LOG_EVERY_N_VARNAME(base, line) LOG_EVERY_N_VARNAME_CONCAT(base, line)
672 #define LOG_EVERY_N_VARNAME_CONCAT(base, line) base ## line
673 
674 #define LOG_OCCURRENCES LOG_EVERY_N_VARNAME(occurrences_, __LINE__)
675 #define LOG_OCCURRENCES_MOD_N LOG_EVERY_N_VARNAME(occurrences_mod_n_, __LINE__)
676 
677 #define SOME_KIND_OF_LOG_EVERY_N(severity, n, what_to_do) \
678   static int LOG_OCCURRENCES = 0, LOG_OCCURRENCES_MOD_N = 0; \
679   ++LOG_OCCURRENCES; \
680   if (++LOG_OCCURRENCES_MOD_N > n) LOG_OCCURRENCES_MOD_N -= n; \
681   if (LOG_OCCURRENCES_MOD_N == 1) \
682     LogMessage(__FILE__, __LINE__, severity, LOG_OCCURRENCES, \
683                &what_to_do).stream()
684 
685 #define SOME_KIND_OF_LOG_IF_EVERY_N(severity, condition, n, what_to_do) \
686   static int LOG_OCCURRENCES = 0, LOG_OCCURRENCES_MOD_N = 0; \
687   ANNOTATE_BENIGN_RACE(&LOG_OCCURRENCES, "logging"); \
688   ANNOTATE_BENIGN_RACE(&LOG_OCCURRENCES_MOD_N, "logging"); \
689   ++LOG_OCCURRENCES; \
690   if (condition && \
691       ((LOG_OCCURRENCES_MOD_N=(LOG_OCCURRENCES_MOD_N + 1) % n) == (1 % n))) \
692     LogMessage(__FILE__, __LINE__, severity, LOG_OCCURRENCES, \
693                  &what_to_do).stream()
694 
695 #define SOME_KIND_OF_PLOG_EVERY_N(severity, n, what_to_do) \
696   static int LOG_OCCURRENCES = 0, LOG_OCCURRENCES_MOD_N = 0; \
697   ANNOTATE_BENIGN_RACE(&LOG_OCCURRENCES, "logging"); \
698   ANNOTATE_BENIGN_RACE(&LOG_OCCURRENCES_MOD_N, "logging"); \
699   ++LOG_OCCURRENCES; \
700   if (++LOG_OCCURRENCES_MOD_N > n) LOG_OCCURRENCES_MOD_N -= n; \
701   if (LOG_OCCURRENCES_MOD_N == 1) \
702     ErrnoLogMessage(__FILE__, __LINE__, severity, LOG_OCCURRENCES, \
703                     &what_to_do).stream()
704 
705 #define SOME_KIND_OF_LOG_FIRST_N(severity, n, what_to_do) \
706   static int LOG_OCCURRENCES = 0; \
707   ANNOTATE_BENIGN_RACE(&LOG_OCCURRENCES, "logging"); \
708   if (LOG_OCCURRENCES <= n) \
709     ++LOG_OCCURRENCES; \
710   if (LOG_OCCURRENCES <= n) \
711     LogMessage(__FILE__, __LINE__, severity, LOG_OCCURRENCES, \
712                &what_to_do).stream()
713 
714 #define LOG_EVERY_N(severity, n) \
715   COMPILE_ASSERT(severity < NUM_SEVERITIES, \
716                  INVALID_REQUESTED_LOG_SEVERITY); \
717   SOME_KIND_OF_LOG_EVERY_N(severity, (n), LogMessage::SendToLog)
718 
719 #define SYSLOG_EVERY_N(severity, n) \
720   SOME_KIND_OF_LOG_EVERY_N(severity, (n), LogMessage::SendToSyslogAndLog)
721 
722 #define PLOG_EVERY_N(severity, n) \
723   SOME_KIND_OF_PLOG_EVERY_N(severity, (n), LogMessage::SendToLog)
724 
725 #define LOG_FIRST_N(severity, n) \
726   SOME_KIND_OF_LOG_FIRST_N(severity, (n), LogMessage::SendToLog)
727 
728 #define LOG_IF_EVERY_N(severity, condition, n) \
729   SOME_KIND_OF_LOG_IF_EVERY_N(severity, (condition), (n), LogMessage::SendToLog)
730 
731 // We want the special COUNTER value available for LOG_EVERY_X()'ed messages
732 enum PRIVATE_Counter {COUNTER};
733 
734 
735 // Plus some debug-logging macros that get compiled to nothing for production
736 
737 #ifndef NDEBUG
738 
739 #define DLOG(severity) LOG(severity)
740 #define DVLOG(verboselevel) VLOG(verboselevel)
741 #define DLOG_IF(severity, condition) LOG_IF(severity, condition)
742 #define DLOG_EVERY_N(severity, n) LOG_EVERY_N(severity, n)
743 #define DLOG_IF_EVERY_N(severity, condition, n) \
744   LOG_IF_EVERY_N(severity, condition, n)
745 #define DLOG_ASSERT(condition) LOG_ASSERT(condition)
746 
747 // debug-only checking.  not executed in NDEBUG mode.
748 #define DCHECK(condition) CHECK(condition)
749 #define DCHECK_EQ(val1, val2) CHECK_EQ(val1, val2)
750 #define DCHECK_NE(val1, val2) CHECK_NE(val1, val2)
751 #define DCHECK_LE(val1, val2) CHECK_LE(val1, val2)
752 #define DCHECK_LT(val1, val2) CHECK_LT(val1, val2)
753 #define DCHECK_GE(val1, val2) CHECK_GE(val1, val2)
754 #define DCHECK_GT(val1, val2) CHECK_GT(val1, val2)
755 #define DCHECK_STREQ(str1, str2) CHECK_STREQ(str1, str2)
756 #define DCHECK_STRCASEEQ(str1, str2) CHECK_STRCASEEQ(str1, str2)
757 #define DCHECK_STRNE(str1, str2) CHECK_STRNE(str1, str2)
758 #define DCHECK_STRCASENE(str1, str2) CHECK_STRCASENE(str1, str2)
759 
760 #else  // NDEBUG
761 
762 #define DLOG(severity) \
763   true ? (void) 0 : LogMessageVoidify() & LOG(severity)
764 
765 #define DVLOG(verboselevel) \
766   (true || !VLOG_IS_ON(verboselevel)) ?\
767     (void) 0 : LogMessageVoidify() & LOG(INFO)
768 
769 #define DLOG_IF(severity, condition) \
770   (true || !(condition)) ? (void) 0 : LogMessageVoidify() & LOG(severity)
771 
772 #define DLOG_EVERY_N(severity, n) \
773   true ? (void) 0 : LogMessageVoidify() & LOG(severity)
774 
775 #define DLOG_IF_EVERY_N(severity, condition, n) \
776   (true || !(condition))? (void) 0 : LogMessageVoidify() & LOG(severity)
777 
778 #define DLOG_ASSERT(condition) \
779   true ? (void) 0 : LOG_ASSERT(condition)
780 
781 #define DCHECK(condition) \
782   while (false) \
783     CHECK(condition)
784 
785 #define DCHECK_EQ(val1, val2) \
786   while (false) \
787     CHECK_EQ(val1, val2)
788 
789 #define DCHECK_NE(val1, val2) \
790   while (false) \
791     CHECK_NE(val1, val2)
792 
793 #define DCHECK_LE(val1, val2) \
794   while (false) \
795     CHECK_LE(val1, val2)
796 
797 #define DCHECK_LT(val1, val2) \
798   while (false) \
799     CHECK_LT(val1, val2)
800 
801 #define DCHECK_GE(val1, val2) \
802   while (false) \
803     CHECK_GE(val1, val2)
804 
805 #define DCHECK_GT(val1, val2) \
806   while (false) \
807     CHECK_GT(val1, val2)
808 
809 #define DCHECK_STREQ(str1, str2) \
810   while (false) \
811     CHECK_STREQ(str1, str2)
812 
813 #define DCHECK_STRCASEEQ(str1, str2) \
814   while (false) \
815     CHECK_STRCASEEQ(str1, str2)
816 
817 #define DCHECK_STRNE(str1, str2) \
818   while (false) \
819     CHECK_STRNE(str1, str2)
820 
821 #define DCHECK_STRCASENE(str1, str2) \
822   while (false) \
823     CHECK_STRCASENE(str1, str2)
824 
825 
826 #endif  // NDEBUG
827 
828 // Log only in verbose mode.
829 
830 #define VLOG(verboselevel) LOG_IF(INFO, VLOG_IS_ON(verboselevel))
831 
832 #define VLOG_IF(verboselevel, condition) \
833   LOG_IF(INFO, (condition) && VLOG_IS_ON(verboselevel))
834 
835 #define VLOG_EVERY_N(verboselevel, n) \
836   LOG_IF_EVERY_N(INFO, VLOG_IS_ON(verboselevel), n)
837 
838 #define VLOG_IF_EVERY_N(verboselevel, condition, n) \
839   LOG_IF_EVERY_N(INFO, (condition) && VLOG_IS_ON(verboselevel), n)
840 
841 
842 // [MLOG is OBSOLETE - use the more convenient VLOG(n) macros]
843 // Log only when a module-specific value (MODULE_FLAG) has a specific
844 // value.  MODULE_FLAG must be a macro that evaluates to the name of
845 // the flag that you wish to use.  You should '#define MODULE_FLAG
846 // <variable name>' before using this macro.  (For example:
847 //       #define MODULE_FLAG FLAGS_dnsverbose
848 #define MLOG(verboselevel) LOG_IF(INFO, MODULE_FLAG >= (verboselevel))
849 
850 // Redefine the standard assert to use our nice log files
851 #undef assert
852 #define assert(x) DLOG_ASSERT(x)
853 
854 //
855 // This class more or less represents a particular log message.  You
856 // create an instance of LogMessage and then stream stuff to it.
857 // When you finish streaming to it, ~LogMessage is called and the
858 // full message gets streamed to the appropriate destination.
859 //
860 // You shouldn't actually use LogMessage's constructor to log things,
861 // though.  You should use the LOG() macro (and variants thereof)
862 // above.
863 class LogMessage {
864 public:
865   enum {
866     // Passing kNoLogPrefix for the line number disables the
867     // log-message prefix. Useful for using the LogMessage
868     // infrastructure as a printing utility. See also the --log_prefix
869     // flag for controlling the log-message prefix on an
870     // application-wide basis.
871     kNoLogPrefix = -1
872   };
873 
874   class LogStream : public ostrstream {
875   public:
LogStream(char * buf,int len,int ctr)876     LogStream(char *buf, int len, int ctr)
877       : ostrstream(buf, len),
878         ctr_(ctr) {
879       self_ = this;
880     }
881 
ctr()882     int ctr() const { return ctr_; }
set_ctr(int ctr)883     void set_ctr(int ctr) { ctr_ = ctr; }
self()884     LogStream* self() const { return self_; }
885 
886   private:
887     int ctr_;  // Counter hack (for the LOG_EVERY_X() macro)
888     LogStream *self_;  // Consistency check hack
889   };
890 
891 public:
892   // icc 8 requires this typedef to avoid an internal compiler error.
893   typedef void (LogMessage::*SendMethod)();
894 
895   LogMessage(const char* file, int line, LogSeverity severity, int ctr,
896              SendMethod send_method);
897 
898   // Two special constructors that generate reduced amounts of code at
899   // LOG call sites for common cases.
900 
901   // Used for LOG(INFO): Implied are:
902   // severity = INFO, ctr = 0, send_method = &LogMessage::SendToLog.
903   //
904   // Using this constructor instead of the more complex constructor above
905   // saves 19 bytes per call site.
906   LogMessage(const char* file, int line);
907 
908   // Used for LOG(severity) where severity != INFO.  Implied
909   // are: ctr = 0, send_method = &LogMessage::SendToLog
910   //
911   // Using this constructor instead of the more complex constructor above
912   // saves 17 bytes per call site.
913   LogMessage(const char* file, int line, LogSeverity severity);
914 
915   // Constructor to log this message to a specified sink (if not NULL).
916   // Implied are: ctr = 0, send_method = &LogMessage::SendToSinkAndLog if
917   // also_send_to_log is true, send_method = &LogMessage::SendToSink otherwise.
918   LogMessage(const char* file, int line, LogSeverity severity, LogSink* sink,
919              bool also_send_to_log);
920 
921   // Constructor where we also give a vector<string> pointer
922   // for storing the messages (if the pointer is not NULL).
923   // Implied are: ctr = 0, send_method = &LogMessage::SaveOrSendToLog.
924   LogMessage(const char* file, int line, LogSeverity severity,
925              vector<string>* outvec);
926 
927   // Constructor where we also give a string pointer for storing the
928   // message (if the pointer is not NULL).  Implied are: ctr = 0,
929   // send_method = &LogMessage::WriteToStringAndLog.
930   LogMessage(const char* file, int line, LogSeverity severity,
931              string* message);
932 
933   // A special constructor used for check failures
934   LogMessage(const char* file, int line, const CheckOpString& result);
935 
936   ~LogMessage();
937 
938   // Flush a buffered message to the sink set in the constructor.  Always
939   // called by the destructor, it may also be called from elsewhere if
940   // needed.  Only the first call is actioned; any later ones are ignored.
941   void Flush();
942 
943   // An arbitrary limit on the length of a single log message.  This
944   // is so that streaming can be done more efficiently.
945   static const size_t kMaxLogMessageLen;
946 
947   // Theses should not be called directly outside of logging.*,
948   // only passed as SendMethod arguments to other LogMessage methods:
949   void SendToLog();  // Actually dispatch to the logs
950   void SendToSyslogAndLog();  // Actually dispatch to syslog and the logs
951 
952   // Call abort() or similar to perform LOG(FATAL) crash.
953   // Writes current stack trace to stderr.
954   static void Fail() ATTRIBUTE_NORETURN;
955 
956   // Same as Fail(), but without writing out the stack trace.
957   // It is assumed that the caller has already generated and
958   // written the trace as appropriate.
959   static void FailWithoutStackTrace() ATTRIBUTE_NORETURN;
960 
961   // Similar to FailWithoutStackTrace(), but without abort()ing.
962   // Terminates the process with error exit code.
963   static void FailQuietly() ATTRIBUTE_NORETURN;
964 
stream()965   ostream& stream() { return *(data_->stream_); }
966 
preserved_errno()967   int preserved_errno() const { return data_->preserved_errno_; }
968 
969   // Must be called without the log_mutex held.  (L < log_mutex)
970   static int64 num_messages(int severity);
971 
972 private:
973   // Fully internal SendMethod cases:
974   void SendToSinkAndLog();  // Send to sink if provided and dispatch to the logs
975   void SendToSink();  // Send to sink if provided, do nothing otherwise.
976 
977   // Write to string if provided and dispatch to the logs.
978   void WriteToStringAndLog();
979 
980   void SaveOrSendToLog();  // Save to stringvec if provided, else to logs
981 
982   void Init(const char* file, int line, LogSeverity severity,
983             void (LogMessage::*send_method)());
984 
985   // Used to fill in crash information during LOG(FATAL) failures.
986   void RecordCrashReason(base::CrashReason* reason);
987 
988   // Counts of messages sent at each priority:
989   static int64 num_messages_[NUM_SEVERITIES];  // under log_mutex
990 
991   // We keep the data in a separate struct so that each instance of
992   // LogMessage uses less stack space.
993   struct LogMessageData {
LogMessageDataLogMessageData994     LogMessageData() {};
995 
996     int preserved_errno_;         // errno at Init() time
997     scoped_array<char> buf_;      // buffer space for non FATAL messages
998     char* message_text_;          // Complete message text
999     scoped_ptr<LogStream> stream_alloc_;
1000     LogStream* stream_;
1001     char severity_;               // level of LogMessage (ex. I, W, E, F)
1002     int line_;                    // line number of file that called LOG
1003     void (LogMessage::*send_method_)();  // Call this in destructor to send
1004     union {  // At most one of these is used: union to keep the size low.
1005       LogSink* sink_;             // NULL or sink to send message to
1006       vector<string>* outvec_;    // NULL or vector to push message onto
1007       string* message_;           // NULL or string to write message into
1008     };
1009     time_t timestamp_;            // Time of creation of LogMessage
1010     struct tm tm_time_;           // Time of creation of LogMessage
1011     size_t num_prefix_chars_;     // # of chars of prefix in this message
1012     size_t num_chars_to_log_;     // # of chars of msg to send to log
1013     size_t num_chars_to_syslog_;  // # of chars of msg to send to syslog
1014     const char* basename_;        // basename of file that called LOG
1015     const char* fullname_;        // fullname of file that called LOG
1016     bool has_been_flushed_;       // false => data has not been flushed
1017     bool first_fatal_;            // true => this was first fatal msg
1018 
1019    private:
1020     DISALLOW_EVIL_CONSTRUCTORS(LogMessageData);
1021   };
1022 
1023   static LogMessageData fatal_msg_data_exclusive_;
1024   static LogMessageData fatal_msg_data_shared_;
1025 
1026   scoped_ptr<LogMessageData> allocated_;
1027   LogMessageData* data_;
1028 
1029   friend class LogDestination;
1030 
1031   DISALLOW_EVIL_CONSTRUCTORS(LogMessage);
1032 
1033 protected:
1034   // Default false; if true, all failures should be as quiet as possible. This
1035   // is stored in LogMessage, rather than LogMessageData, because all FATAL-
1036   // level handlers share the same LogMessageData for signal safety reasons.
1037   bool fail_quietly_;
1038 };
1039 
1040 // This class happens to be thread-hostile because all instances share
1041 // a single data buffer, but since it can only be created just before
1042 // the process dies, we don't worry so much.
1043 class LogMessageFatal : public LogMessage {
1044  public:
1045   LogMessageFatal(const char* file, int line);
1046   LogMessageFatal(const char* file, int line, const CheckOpString& result);
1047   ~LogMessageFatal() ATTRIBUTE_NORETURN;
1048 };
1049 
1050 class LogMessageQuietlyFatal : public LogMessage {
1051  public:
1052   LogMessageQuietlyFatal(const char* file, int line);
1053   LogMessageQuietlyFatal(const char* file, int line,
1054                          const CheckOpString& result);
1055   ~LogMessageQuietlyFatal() ATTRIBUTE_NORETURN;
1056 };
1057 
1058 // A non-macro interface to the log facility; (useful
1059 // when the logging level is not a compile-time constant).
LogAtLevel(int const severity,string const & msg)1060 inline void LogAtLevel(int const severity, string const &msg) {
1061   LogMessage(__FILE__, __LINE__, severity).stream() << msg;
1062 }
1063 
1064 // A macro alternative of LogAtLevel. New code may want to use this
1065 // version since there are two advantages: 1. this version outputs the
1066 // file name and the line number where this macro is put like other
1067 // LOG macros, 2. this macro can be used as C++ stream.
1068 #define LOG_AT_LEVEL(severity) LogMessage(__FILE__, __LINE__, severity).stream()
1069 
1070 // Helpers for CHECK_NOTNULL(). Two are necessary to support both raw pointers
1071 // and smart pointers.
1072 template <typename T>
CheckNotNull(const char * file,int line,const char * names,T * t)1073 T* CheckNotNull(const char *file, int line, const char *names, T* t) {
1074   return CheckNotNullCommon(file, line, names, t);
1075 }
1076 
1077 template <typename T>
CheckNotNull(const char * file,int line,const char * names,T & t)1078 T& CheckNotNull(const char *file, int line, const char *names, T& t) {
1079   return CheckNotNullCommon(file, line, names, t);
1080 }
1081 
1082 template <typename T>
CheckNotNullCommon(const char * file,int line,const char * names,T & t)1083 T& CheckNotNullCommon(const char *file, int line, const char *names, T& t) {
1084   if (t == NULL) {
1085     LogMessageFatal(file, line, new string(names));
1086   }
1087   return t;
1088 }
1089 
1090 // Allow folks to put a counter in the LOG_EVERY_X()'ed messages. This
1091 // only works if ostream is a LogStream. If the ostream is not a
1092 // LogStream you'll get an assert saying as much at runtime.
1093 ostream& operator<<(ostream &os, const PRIVATE_Counter&);
1094 
1095 
1096 // We need to be able to stream DocIds.  But if DocIds are the same as
1097 // a built-in type, don't try to redefine things that are already
1098 // defined!
1099 #ifndef NDEBUG
1100 inline ostream& operator<<(ostream& o, const DocId& d) {
1101   return (o << DocidForPrintf(d));
1102 }
1103 
1104 inline ostream& operator<<(ostream& o, const DocId32Bit& d) {
1105   return (o << Docid32BitForPrintf(d));
1106 }
1107 #endif  // NDEBUG
1108 
1109 
1110 // Derived class for PLOG*() above.
1111 class ErrnoLogMessage : public LogMessage {
1112  public:
1113 
1114   ErrnoLogMessage(const char* file, int line, LogSeverity severity, int ctr,
1115                   void (LogMessage::*send_method)());
1116 
1117   // Postpends ": strerror(errno) [errno]".
1118   ~ErrnoLogMessage();
1119 
1120  private:
1121 
1122   DISALLOW_EVIL_CONSTRUCTORS(ErrnoLogMessage);
1123 };
1124 
1125 
1126 // This class is used to explicitly ignore values in the conditional
1127 // logging macros.  This avoids compiler warnings like "value computed
1128 // is not used" and "statement has no effect".
1129 
1130 class LogMessageVoidify {
1131  public:
LogMessageVoidify()1132   LogMessageVoidify() { }
1133   // This has to be an operator with a precedence lower than << but
1134   // higher than ?:
1135   void operator&(ostream&) { }
1136 };
1137 
1138 
1139 // Flushes all log files that contains messages that are at least of
1140 // the specified severity level.  Thread-safe.
1141 void FlushLogFiles(LogSeverity min_severity);
1142 
1143 // Flushes all log files that contains messages that are at least of
1144 // the specified severity level. Thread-hostile because it ignores
1145 // locking -- used for catastrophic failures.
1146 void FlushLogFilesUnsafe(LogSeverity min_severity);
1147 
1148 //
1149 // Set the destination to which a particular severity level of log
1150 // messages is sent.  If base_filename is "", it means "don't log this
1151 // severity".  Thread-safe.
1152 //
1153 void SetLogDestination(LogSeverity severity, const char* base_filename);
1154 
1155 //
1156 // Set the basename of the symlink to the latest log file at a given
1157 // severity.  If symlink_basename is empty, do not make a symlink.  If
1158 // you don't call this function, the symlink basename is the
1159 // invocation name of the program.  Thread-safe.
1160 //
1161 void SetLogSymlink(LogSeverity severity, const char* symlink_basename);
1162 
1163 //
1164 // Used to send logs to some other kind of destination
1165 // Users should subclass LogSink and override send to do whatever they want.
1166 // Implementations must be thread-safe because a shared instance will
1167 // be called from whichever thread ran the LOG(XXX) line.
1168 class LogSink {
1169  public:
1170   virtual ~LogSink();
1171 
1172   // Sink's logging logic (message_len is such as to exclude '\n' at the end).
1173   // This method can't use LOG() or CHECK() as logging system mutex(s) are held
1174   // during this call.
1175   virtual void send(LogSeverity severity, const char* full_filename,
1176                     const char* base_filename, int line,
1177                     const struct tm* tm_time,
1178                     const char* message, size_t message_len) = 0;
1179 
1180   // Redefine this to implement waiting for
1181   // the sink's logging logic to complete.
1182   // It will be called after each send() returns,
1183   // but before that LogMessage exits or crashes.
1184   // By default this function does nothing.
1185   // Using this function one can implement complex logic for send()
1186   // that itself involves logging; and do all this w/o causing deadlocks and
1187   // inconsistent rearrangement of log messages.
1188   // E.g. if a LogSink has thread-specific actions, the send() method
1189   // can simply add the message to a queue and wake up another thread that
1190   // handles real logging while itself making some LOG() calls;
1191   // WaitTillSent() can be implemented to wait for that logic to complete.
1192   // See our unittest for an example.
1193   virtual void WaitTillSent();
1194 
1195   // Returns the normal text output of the log message.
1196   // Can be useful to implement send().
1197   static string ToString(LogSeverity severity, const char* file, int line,
1198                          const struct tm* tm_time,
1199                          const char* message, size_t message_len);
1200 };
1201 
1202 // Add or remove a LogSink as a consumer of logging data.  Thread-safe.
1203 void AddLogSink(LogSink *destination);
1204 void RemoveLogSink(LogSink *destination);
1205 
1206 //
1207 // Specify an "extension" added to the filename specified via
1208 // SetLogDestination.  This applies to all severity levels.  It's
1209 // often used to append the port we're listening on to the logfile
1210 // name.  Thread-safe.
1211 //
1212 void SetLogFilenameExtension(const char* filename_extension);
1213 
1214 //
1215 // Make it so that all log messages of at least a particular severity
1216 // are logged to stderr (in addition to logging to the usual log
1217 // file(s)).  Thread-safe.
1218 //
1219 void SetStderrLogging(LogSeverity min_severity);
1220 
1221 //
1222 // Make it so that all log messages go only to stderr.  Thread-safe.
1223 //
1224 void LogToStderr();
1225 
1226 //
1227 // Make it so that all log messages of at least a particular severity are
1228 // logged via email to a list of addresses (in addition to logging to the
1229 // usual log file(s)).  The list of addresses is just a string containing
1230 // the email addresses to send to (separated by spaces, say).
1231 //
1232 // Beyond thread-hostile.  This function enables email logging,
1233 // which calls popen() if any log messages are actually mailed.
1234 // A multi-thread program which calls this function, even in a single thread,
1235 // will randomly hang if it logs any messages which are mailed.
1236 void SetEmailLogging(LogSeverity min_severity, const char* addresses);
1237 
1238 //
1239 // Generate a special "status" message.  This will be useful to
1240 // monitoring scripts that want to know about the progress of
1241 // a long-running program.  The two supplied arguments should have
1242 // identical units.  The "done" argument says how much work has
1243 // been completed, and the "total" argument says how much total
1244 // work has to be done.  Thread-hostile if
1245 // FLAGS_status_messages_to_status_file.  Thread-safe otherwise.
1246 //
1247 void StatusMessage(int64 done, int64 total);
1248 
1249 // Like StatusMessage(), only writes the status to the file ./STATUS
1250 // Intended to make life easier for processes running on the global
1251 // work queue, where the standard status message file is ./STATUS.
1252 // Thread-hostile.
1253 void GWQStatusMessage(const char* msg);
1254 
1255 // A simple function that sends email. dest is a comma-separated
1256 // list of addressess.
1257 //
1258 // Beyond thread-hostile.  This function calls popen().
1259 // A multi-thread program which calls this function, even in a single thread,
1260 // will randomly hang.
1261 bool SendEmail(const char*dest, const char *subject, const char*body);
1262 
1263 // Return the set of directories to try generating a log file into.
1264 // Thread-hostile, but expected to only be called from InitGoogle.
1265 const vector<string>& GetLoggingDirectories();
1266 
1267 // For tests only:  Clear the internal [cached] list of logging directories to
1268 // force a refresh the next time GetLoggingDirectories is called.
1269 // Thread-hostile.
1270 void TestOnly_ClearLoggingDirectoriesList();
1271 
1272 // Returns a set of existing temporary directories, which will be a
1273 // subset of the directories returned by GetLogginDirectories().
1274 // Thread-safe.
1275 void GetExistingTempDirectories(vector<string>* list);
1276 
1277 // Print any fatal message again -- useful to call from signal handler
1278 // so that the last thing in the output is the fatal message.
1279 // Thread-hostile, but a race is unlikely.
1280 void ReprintFatalMessage();
1281 
1282 // Truncate a log file that may be the append-only output of multiple
1283 // processes and hence can't simply be renamed/reopened (typically a
1284 // stdout/stderr).  If the file "path" is > "limit" bytes, copy the
1285 // last "keep" bytes to offset 0 and truncate the rest. Since we could
1286 // be racing with other writers, this approach has the potential to
1287 // lose very small amounts of data. For security, only follow symlinks
1288 // if the path is /proc/self/fd/*
1289 void TruncateLogFile(const char *path, int64 limit, int64 keep);
1290 
1291 // Truncate stdout and stderr if they are over the value specified by
1292 // --max_log_size; keep the final 1MB.  This function has the same
1293 // race condition as TruncateLogFile.
1294 void TruncateStdoutStderr();
1295 
1296 // Return the string representation of the provided LogSeverity level.
1297 // Thread-safe.
1298 const char* GetLogSeverityName(LogSeverity severity);
1299 
1300 // ---------------------------------------------------------------------
1301 // Implementation details that are not useful to most clients
1302 // ---------------------------------------------------------------------
1303 
1304 // A Logger is the interface used by logging modules (base/logging.cc
1305 // and file/logging/blog.cc) to emit entries to a log.  A typical
1306 // implementation will dump formatted data to a sequence of files.  We
1307 // also provide interfaces that will forward the data to another
1308 // thread so that the invoker never blocks.  Implementations should be
1309 // thread-safe since the logging system will write to them from
1310 // multiple threads.
1311 
1312 namespace base {
1313 
1314 class Logger {
1315  public:
1316   virtual ~Logger();
1317 
1318   // Writes "message[0,message_len-1]" corresponding to an event that
1319   // occurred at "timestamp".  If "force_flush" is true, the log file
1320   // is flushed immediately.
1321   //
1322   // The input message has already been formatted as deemed
1323   // appropriate by the higher level logging facility.  For example,
1324   // textual log messages already contain timestamps, and the
1325   // file:linenumber header.
1326   virtual void Write(bool force_flush,
1327                      time_t timestamp,
1328                      const char* message,
1329                      int message_len) = 0;
1330 
1331   // Flush any buffered messages
1332   virtual void Flush() = 0;
1333 
1334   // Get the current LOG file size.
1335   // The returned value is approximate since some
1336   // logged data may not have been flushed to disk yet.
1337   virtual uint32 LogSize() = 0;
1338 };
1339 
1340 // Get the logger for the specified severity level.  The logger
1341 // remains the property of the logging module and should not be
1342 // deleted by the caller.  Thread-safe.
1343 extern Logger* GetLogger(LogSeverity level);
1344 
1345 // Set the logger for the specified severity level.  The logger
1346 // becomes the property of the logging module and should not
1347 // be deleted by the caller.  Thread-safe.
1348 extern void SetLogger(LogSeverity level, Logger* logger);
1349 
1350 }
1351 
1352 // glibc has traditionally implemented two incompatible versions of
1353 // strerror_r(). There is a poorly defined convention for picking the
1354 // version that we want, but it is not clear whether it even works with
1355 // all versions of glibc.
1356 // So, instead, we provide this wrapper that automatically detects the
1357 // version that is in use, and then implements POSIX semantics.
1358 // N.B. In addition to what POSIX says, we also guarantee that "buf" will
1359 // be set to an empty string, if this function failed. This means, in most
1360 // cases, you do not need to check the error code and you can directly
1361 // use the value of "buf". It will never have an undefined value.
1362 int posix_strerror_r(int err, char *buf, size_t len);
1363 
1364 
1365 // A class for which we define operator<<, which does nothing.
1366 class NullStream : public LogMessage::LogStream {
1367  public:
1368   // Initialize the LogStream so the messages can be written somewhere
1369   // (they'll never be actually displayed). This will be needed if a
1370   // NullStream& is implicitly converted to LogStream&, in which case
1371   // the overloaded NullStream::operator<< will not be invoked.
NullStream()1372   NullStream() : LogMessage::LogStream(message_buffer_, 1, 0) { }
NullStream(const char *,int,const CheckOpString &)1373   NullStream(const char* /*file*/, int /*line*/,
1374              const CheckOpString& /*result*/) :
1375       LogMessage::LogStream(message_buffer_, 1, 0) { }
stream()1376   NullStream &stream() { return *this; }
1377  private:
1378   // A very short buffer for messages (which we discard anyway). This
1379   // will be needed if NullStream& converted to LogStream& (e.g. as a
1380   // result of a conditional expression).
1381   char message_buffer_[2];
1382 };
1383 
1384 // Do nothing. This operator is inline, allowing the message to be
1385 // compiled away. The message will not be compiled away if we do
1386 // something like (flag ? LOG(INFO) : LOG(ERROR)) << message; when
1387 // SKIP_LOG=WARNING. In those cases, NullStream will be implicitly
1388 // converted to LogStream and the message will be computed and then
1389 // quietly discarded.
1390 template<class T>
1391 inline NullStream& operator<<(NullStream &str, const T &value) { return str; }
1392 
1393 // Similar to NullStream, but aborts the program (without stack
1394 // trace), like LogMessageFatal.
1395 class NullStreamFatal : public NullStream {
1396  public:
NullStreamFatal()1397   NullStreamFatal() { }
NullStreamFatal(const char * file,int line,const CheckOpString & result)1398   NullStreamFatal(const char* file, int line, const CheckOpString& result) :
1399       NullStream(file, line, result) { }
~NullStreamFatal()1400   ~NullStreamFatal() ATTRIBUTE_NORETURN { _exit(1); }
1401 };
1402 
1403 #endif // _LOGGING_H_
1404