/* -*- 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 "Log.hpp" #include "Util.hpp" namespace Log { using namespace Poco; /// Helper to avoid destruction ordering issues. static struct StaticHelper { private: Poco::Logger* _logger; static thread_local Poco::Logger* _threadLocalLogger; std::string _name; std::string _logLevel; std::string _id; std::atomic _inited; public: StaticHelper() : _logger(nullptr), _inited(true) { } ~StaticHelper() { _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 setLevel(const std::string& logLevel) { _logLevel = logLevel; } const std::string& getLevel() const { return _logLevel; } void setLogger(Poco::Logger* logger) { _logger = logger; }; void setThreadLocalLogger(Poco::Logger* logger) { // FIXME: What to do with the previous thread-local logger, if any? Will deleting it // destroy also its channel? That won't be good as we use the same channel for all // loggers. Best to just leak it? _threadLocalLogger = logger; } Poco::Logger* getLogger() const { return _logger; } Poco::Logger* getThreadLocalLogger() const { return _threadLocalLogger; } } Static; thread_local Poco::Logger* StaticHelper::_threadLocalLogger = nullptr; 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, std::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, std::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) { #if defined(IOS) || defined(__FreeBSD__) // 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((Static.getInited() ? Static.getId().c_str() : ""), buffer); *pos++ = '-'; // Thread ID. const auto osTid = Util::getThreadId(); #if defined(__linux__) // On Linux osTid is pid_t. 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; } #else // On all other systems osTid is std::thread::id. std::stringstream ss; ss << osTid; pos = strcopy(ss.str().c_str(), pos); #endif *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) { Static.setName(name); std::ostringstream oss; oss << Static.getName(); #if !MOBILEAPP // Just one process in a mobile app, the pid is uninteresting. oss << '-' << std::setw(5) << std::setfill('0') << getpid(); #endif Static.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(); try { auto& logger = Poco::Logger::create(Static.getName(), channel, Poco::Message::PRIO_TRACE); Static.setLogger(&logger); } catch (ExistsException&) { auto& logger = Poco::Logger::get(Static.getName()); Static.setLogger(&logger); } auto logger = Static.getLogger(); const std::string level = logLevel.empty() ? std::string("trace") : logLevel; logger->setLevel(level); Static.setLevel(level); 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 = Static.getThreadLocalLogger(); if (pLogger != nullptr) return *pLogger; pLogger = Static.getLogger(); return pLogger ? *pLogger : Poco::Logger::get(Static.getInited() ? Static.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 } void setThreadLocalLogLevel(const std::string& logLevel) { if (!Static.getLogger()) { return; } // Use the same channel for all Poco loggers. auto channel = Static.getLogger()->getChannel(); // The Poco loggers have to have names that are unique, but those aren't displayed anywhere. // So just use the name of the default logger for this process plus a counter. static int counter = 1; auto& logger = Poco::Logger::create(Static.getName() + "." + std::to_string(counter++), channel, Poco::Logger::parseLevel(logLevel)); Static.setThreadLocalLogger(&logger); } const std::string& getLevel() { return Static.getLevel(); } } /* vim:set shiftwidth=4 softtabstop=4 expandtab: */