/* This file is part of Strawberry. Copyright 2011, David Sansome Licensed under the Apache License, Version 2.0 (the "License"); you may not use this file except in compliance with the License. You may obtain a copy of the License at http://www.apache.org/licenses/LICENSE-2.0 Unless required by applicable law or agreed to in writing, software distributed under the License is distributed on an "AS IS" BASIS, WITHOUT WARRANTIES OR CONDITIONS OF ANY KIND, either express or implied. See the License for the specific language governing permissions and limitations under the License. */ #include #include "config.h" #include #include #include #include #include #include #include #ifdef HAVE_BACKTRACE # include #endif #include #include #include #include #include #include #include #include #include #include #include #include #include "logging.h" namespace logging { static Level sDefaultLevel = Level_Debug; static QMap* sClassLevels = nullptr; static QIODevice *sNullDevice = nullptr; //const char* kDefaultLogLevels = "*:3"; const char* kDefaultLogLevels = "GstEnginePipeline:2,*:3"; static const char *kMessageHandlerMagic = "__logging_message__"; static const int kMessageHandlerMagicLength = strlen(kMessageHandlerMagic); static QtMessageHandler sOriginalMessageHandler = nullptr; template static T CreateLogger(Level level, const QString& class_name, int line, const char* category); void GLog(const char *domain, int level, const char *message, void*) { switch (level) { case G_LOG_FLAG_RECURSION: case G_LOG_FLAG_FATAL: case G_LOG_LEVEL_ERROR: case G_LOG_LEVEL_CRITICAL: qLogCat(Error, domain) << message; break; case G_LOG_LEVEL_WARNING: qLogCat(Warning, domain) << message; break; case G_LOG_LEVEL_MESSAGE: case G_LOG_LEVEL_INFO: qLogCat(Info, domain) << message; break; case G_LOG_LEVEL_DEBUG: default: qLogCat(Debug, domain) << message; break; } } template class DebugBase : public QDebug { public: DebugBase() : QDebug(sNullDevice) {} DebugBase(QtMsgType t) : QDebug(t) {} T& space() { return static_cast(QDebug::space()); } T& noSpace() { return static_cast(QDebug::nospace()); } }; // Debug message will be stored in a buffer. class BufferedDebug : public DebugBase { public: BufferedDebug() : DebugBase() {} BufferedDebug(QtMsgType) : DebugBase(), buf_(new QBuffer, later_deleter) { buf_->open(QIODevice::WriteOnly); // QDebug doesn't have a method to set a new io device, but swap() allows the devices to be swapped between two instances. QDebug other(buf_.get()); swap(other); } // Delete function for the buffer. Since a base class is holding a reference to the raw pointer, // it shouldn't be deleted until after the deletion of this object is complete. static void later_deleter(QBuffer* b) { b->deleteLater(); } std::shared_ptr buf_; }; // Debug message will be logged immediately. class LoggedDebug : public DebugBase { public: LoggedDebug() : DebugBase() {} LoggedDebug(QtMsgType t) : DebugBase(t) { nospace() << kMessageHandlerMagic; } }; static void MessageHandler(QtMsgType type, const QMessageLogContext&, const QString &message) { if (strncmp(kMessageHandlerMagic, message.toLocal8Bit().data(), kMessageHandlerMagicLength) == 0) { fprintf(stderr, "%s\n", message.toLocal8Bit().data() + kMessageHandlerMagicLength); return; } Level level = Level_Debug; switch (type) { case QtFatalMsg: case QtCriticalMsg: level = Level_Error; break; case QtWarningMsg: level = Level_Warning; break; case QtDebugMsg: default: level = Level_Debug; break; } for (const QString& line : message.split('\n')) { BufferedDebug d = CreateLogger(level, "unknown", -1, nullptr); d << line.toLocal8Bit().constData(); if (d.buf_) { d.buf_->close(); fprintf(stderr, "%s\n", d.buf_->buffer().data()); } } if (type == QtFatalMsg) { abort(); } } void Init() { delete sClassLevels; delete sNullDevice; sClassLevels = new QMap(); sNullDevice = new NullDevice; sNullDevice->open(QIODevice::ReadWrite); // Catch other messages from Qt if (!sOriginalMessageHandler) { sOriginalMessageHandler = qInstallMessageHandler(MessageHandler); } } void SetLevels(const QString &levels) { if (!sClassLevels) return; for (const QString& item : levels.split(',')) { const QStringList class_level = item.split(':'); QString class_name; bool ok = false; int level = Level_Error; if (class_level.count() == 1) { level = class_level.last().toInt(&ok); } else if (class_level.count() == 2) { class_name = class_level.first(); level = class_level.last().toInt(&ok); } if (!ok || level < Level_Error || level > Level_Debug) { continue; } if (class_name.isEmpty() || class_name == "*") { sDefaultLevel = (Level) level; } else { sClassLevels->insert(class_name, (Level) level); } } } static QString ParsePrettyFunction(const char *pretty_function) { // Get the class name out of the function name. QString class_name = pretty_function; const int paren = class_name.indexOf('('); if (paren != -1) { const int colons = class_name.lastIndexOf("::", paren); if (colons != -1) { class_name = class_name.left(colons); } else { class_name = class_name.left(paren); } } const int space = class_name.lastIndexOf(' '); if (space != -1) { class_name = class_name.mid(space+1); } return class_name; } template static T CreateLogger(Level level, const QString &class_name, int line, const char* category) { // Map the level to a string const char *level_name = nullptr; switch (level) { case Level_Debug: level_name = " DEBUG "; break; case Level_Info: level_name = " INFO "; break; case Level_Warning: level_name = " WARN "; break; case Level_Error: level_name = " ERROR "; break; case Level_Fatal: level_name = " FATAL "; break; } QString filter_category = (category != nullptr) ? category : class_name; // Check the settings to see if we're meant to show or hide this message. Level threshold_level = sDefaultLevel; if (sClassLevels && sClassLevels->contains(filter_category)) { threshold_level = sClassLevels->value(filter_category); } if (level > threshold_level) { return T(); } QString function_line = class_name; if (line != -1) { function_line += ":" + QString::number(line); } if (category) { function_line += "(" + QString(category) + ")"; } QtMsgType type = QtDebugMsg; if (level == Level_Fatal) { type = QtFatalMsg; } T ret(type); ret.nospace() << QDateTime::currentDateTime().toString("hh:mm:ss.zzz").toLatin1().constData() << level_name << function_line.leftJustified(32).toLatin1().constData(); return ret.space(); } QString CXXDemangle(const QString &mangled_function); QString CXXDemangle(const QString &mangled_function) { int status; char* demangled_function = abi::__cxa_demangle(mangled_function.toLatin1().constData(), nullptr, nullptr, &status); if (status == 0) { QString ret = QString::fromLatin1(demangled_function); free(demangled_function); return ret; } return mangled_function; // Probably not a C++ function. } QString DarwinDemangle(const QString &symbol); QString DarwinDemangle(const QString &symbol) { QStringList split = symbol.split(' ', QString::SkipEmptyParts); QString mangled_function = split[3]; return CXXDemangle(mangled_function); } QString LinuxDemangle(const QString &symbol); QString LinuxDemangle(const QString &symbol) { QRegExp regex("\\(([^+]+)"); if (!symbol.contains(regex)) { return symbol; } QString mangled_function = regex.cap(1); return CXXDemangle(mangled_function); } QString DemangleSymbol(const QString &symbol); QString DemangleSymbol(const QString &symbol) { #ifdef Q_OS_MACOS return DarwinDemangle(symbol); #elif defined(Q_OS_LINUX) return LinuxDemangle(symbol); #else return symbol; #endif } void DumpStackTrace() { #ifdef HAVE_BACKTRACE void* callstack[128]; int callstack_size = backtrace(reinterpret_cast(&callstack), sizeof(callstack)); char** symbols = backtrace_symbols(reinterpret_cast(&callstack), callstack_size); // Start from 1 to skip ourself. for (int i = 1; i < callstack_size; ++i) { std::cerr << DemangleSymbol(QString::fromLatin1(symbols[i])).toStdString() << std::endl; } free(symbols); #else qLog(Debug) << "FIXME: Implement printing stack traces on this platform"; #endif } // These are the functions that create loggers for the rest of Clementine. // It's okay that the LoggedDebug instance is copied to a QDebug in these. It // doesn't override any behavior that should be needed after return. #define qCreateLogger(line, pretty_function, category, level) logging::CreateLogger(logging::Level_##level, logging::ParsePrettyFunction(pretty_function), line, category) QDebug CreateLoggerFatal(int line, const char *pretty_function, const char* category) { return qCreateLogger(line, pretty_function, category, Fatal); } QDebug CreateLoggerError(int line, const char *pretty_function, const char* category) { return qCreateLogger(line, pretty_function, category, Error); } #ifdef QT_NO_WARNING_OUTPUT QNoDebug CreateLoggerWarning(int, const char*, const char*) { return QNoDebug(); } #else QDebug CreateLoggerWarning(int line, const char *pretty_function, const char* category) { return qCreateLogger(line, pretty_function, category, Warning); } #endif // QT_NO_WARNING_OUTPUT #ifdef QT_NO_DEBUG_OUTPUT QNoDebug CreateLoggerInfo(int, const char*, const char*) { return QNoDebug(); } QNoDebug CreateLoggerDebug(int, const char*, const char*) { return QNoDebug(); } #else QDebug CreateLoggerInfo(int line, const char *pretty_function, const char* category) { return qCreateLogger(line, pretty_function, category, Info); } QDebug CreateLoggerDebug(int line, const char *pretty_function, const char* category) { return qCreateLogger(line, pretty_function, category, Debug); } #endif // QT_NO_DEBUG_OUTPUT } // namespace logging namespace { template QString print_duration(T duration, const std::string& unit) { return QString("%1%2").arg(duration.count()).arg(unit.c_str()); } } // namespace QDebug operator<<(QDebug dbg, std::chrono::seconds secs) { dbg.nospace() << print_duration(secs, "s"); return dbg.space(); }