/* -*- Mode: C++; tab-width: 4; indent-tabs-mode: nil; c-basic-offset: 4; fill-column: 100 -*- */ /* * This Source Code Form is subject to the terms of the Mozilla Public * License, v. 2.0. If a copy of the MPL was not distributed with this * file, You can obtain one at http://mozilla.org/MPL/2.0/. */ #include #ifdef __linux #include #include #endif #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include #include "Log.hpp" #include "Util.hpp" namespace Log { using namespace Poco; /// Helper to avoid destruction ordering issues. struct StaticNameHelper { private: Poco::Logger* _logger; std::string _name; std::string _id; std::atomic _inited; public: StaticNameHelper() : _logger(nullptr), _inited(true) { } ~StaticNameHelper() { _inited = false; } bool getInited() const { return _inited; } void setId(const std::string& id) { _id = id; } const std::string& getId() const { return _id; } void setName(const std::string& name) { _name = name; } const std::string& getName() const { return _name; } void setLogger(Poco::Logger* logger) { _logger = logger; }; Poco::Logger* getLogger() const { return _logger; } }; static StaticNameHelper Source; bool IsShutdown = false; // We need a signal safe means of writing messages // $ man 7 signal void signalLog(const char *message) { while (true) { const int length = std::strlen(message); const int written = write(STDERR_FILENO, message, length); if (written < 0) { if (errno == EINTR) continue; // ignore. else break; } message += written; if (message[0] == '\0') break; } } // We need a signal safe means of writing messages // $ man 7 signal void signalLogNumber(std::size_t num) { int i; char buf[22]; buf[21] = '\0'; for (i = 20; i > 0 && num > 0; --i) { buf[i] = '0' + num % 10; num /= 10; } signalLog(buf + i + 1); } /// Convert an unsigned number to ascii with 0 padding. template void to_ascii_fixed(char* buf, size_t num) { buf[Width - 1] = '0' + num % 10; // Units. if (Width > 1) { num /= 10; buf[Width - 2] = '0' + num % 10; // Tens. } if (Width > 2) { num /= 10; buf[Width - 3] = '0' + num % 10; // Hundreds. } if (Width > 3) { num /= 10; buf[Width - 4] = '0' + num % 10; // Thousands. } if (Width > 4) { num /= 10; buf[Width - 5] = '0' + num % 10; // Ten-Thousands. } if (Width > 5) { num /= 10; buf[Width - 6] = '0' + num % 10; // Hundred-Thousands. } static_assert(Width >= 1 && Width <= 6, "Width is invalid."); } /// Copy a null-terminated string into another. /// Expects the destination to be large enough. /// Note: unlike strcpy, this returns the *new* out /// (destination) pointer, which saves a strlen call. char* strcopy(const char* in, char* out) { while (*in) *out++ = *in++; return out; } /// Convert unsigned long num to base-10 ascii in place. /// Returns the *end* position. char* to_ascii(char* buf, size_t num) { int i = 0; do { buf[i++] = '0' + num % 10; num /= 10; } while (num > 0); // Reverse. for (char *front = buf, *back = buf + i - 1; back > front; ++front, --back) { const char t = *front; *front = *back; *back = t; } return buf + i; } char* prefix(const Poco::DateTime& time, char* buffer, const char* level) { #ifdef IOS // Don't bother with the "Source" which would be just "Mobile" always and non-informative as // there is just one process in the app anyway. char *pos = buffer; // Don't bother with the thread identifier either. We output the thread name which is much // more useful anyway. #else // Note that snprintf is deemed signal-safe in most common implementations. char* pos = strcopy((Source.getInited() ? Source.getId().c_str() : ""), buffer); *pos++ = '-'; // Thread ID. const long osTid = Util::getThreadId(); if (osTid > 99999) { if (osTid > 999999) pos = to_ascii(pos, osTid); else { to_ascii_fixed<6>(pos, osTid); pos += 6; } } else { to_ascii_fixed<5>(pos, osTid); pos += 5; } *pos++ = ' '; #endif // YYYY-MM-DD. to_ascii_fixed<4>(pos, time.year()); pos[4] = '-'; pos += 5; to_ascii_fixed<2>(pos, time.month()); pos[2] = '-'; pos += 3; to_ascii_fixed<2>(pos, time.day()); pos[2] = ' '; pos += 3; // HH:MM:SS.uS to_ascii_fixed<2>(pos, time.hour()); pos[2] = ':'; pos += 3; to_ascii_fixed<2>(pos, time.minute()); pos[2] = ':'; pos += 3; to_ascii_fixed<2>(pos, time.second()); pos[2] = '.'; pos += 3; to_ascii_fixed<6>(pos, time.millisecond() * 1000 + time.microsecond()); pos[6] = ' '; pos[7] = '['; pos[8] = ' '; pos += 9; pos = strcopy(Util::getThreadName(), pos); pos[0] = ' '; pos[1] = ']'; pos[2] = ' '; pos += 3; pos = strcopy(level, pos); pos[0] = ' '; pos[1] = ' '; pos[2] = '\0'; return buffer; } void signalLogPrefix() { char buffer[1024]; prefix(buffer, "SIG"); signalLog(buffer); } void initialize(const std::string& name, const std::string& logLevel, const bool withColor, const bool logToFile, const std::map& config) { Source.setName(name); std::ostringstream oss; oss << Source.getName(); #if !MOBILEAPP // Just one process in a mobile app, the pid is uninteresting. oss << '-' << std::setw(5) << std::setfill('0') << getpid(); #endif Source.setId(oss.str()); // Configure the logger. AutoPtr channel; if (logToFile) { channel = static_cast(new FileChannel("loolwsd.log")); for (const auto& pair : config) { channel->setProperty(pair.first, pair.second); } } else if (withColor) { channel = static_cast(new Poco::ColorConsoleChannel()); channel->setProperty("traceColor", "green"); channel->setProperty("warningColor", "magenta"); } else channel = static_cast(new Poco::ConsoleChannel()); /** * Open the channel explicitly, instead of waiting for first log message * This is important especially for the kit process where opening the channel * after chroot can cause file creation inside the jail instead of outside * */ channel->open(); auto& logger = Poco::Logger::create(Source.getName(), channel, Poco::Message::PRIO_TRACE); Source.setLogger(&logger); logger.setLevel(logLevel.empty() ? std::string("trace") : logLevel); const std::time_t t = std::time(nullptr); oss.str(""); oss.clear(); oss << "Initializing " << name << '.'; // TODO: replace with std::put_time when we move to gcc 5+. char buf[32]; if (strftime(buf, sizeof(buf), "%a %F %T%z", std::localtime(&t)) > 0) { oss << " Local time: " << buf << '.'; } oss << " Log level is [" << logger.getLevel() << "]."; LOG_INF(oss.str()); } Poco::Logger& logger() { Poco::Logger* pLogger = Source.getLogger(); return pLogger ? *pLogger : Poco::Logger::get(Source.getInited() ? Source.getName() : std::string()); } void shutdown() { #if !MOBILEAPP IsShutdown = true; Poco::Logger::shutdown(); // Flush std::flush(std::cout); fflush(stdout); std::flush(std::cerr); fflush(stderr); #endif } } /* vim:set shiftwidth=4 softtabstop=4 expandtab: */