| 1 | // |
| 2 | // Copyright (c) 2002-2010 The ANGLE Project Authors. All rights reserved. |
| 3 | // Use of this source code is governed by a BSD-style license that can be |
| 4 | // found in the LICENSE file. |
| 5 | // |
| 6 | |
| 7 | // debug.cpp: Debugging utilities. |
| 8 | |
| 9 | #include "common/debug.h" |
| 10 | |
| 11 | #include <stdarg.h> |
| 12 | |
| 13 | #include <array> |
| 14 | #include <cstdio> |
| 15 | #include <fstream> |
| 16 | #include <mutex> |
| 17 | #include <ostream> |
| 18 | #include <vector> |
| 19 | |
| 20 | #if defined(ANGLE_PLATFORM_ANDROID) |
| 21 | # include <android/log.h> |
| 22 | #endif |
| 23 | |
| 24 | #include "common/Optional.h" |
| 25 | #include "common/angleutils.h" |
| 26 | |
| 27 | namespace gl |
| 28 | { |
| 29 | |
| 30 | namespace |
| 31 | { |
| 32 | |
| 33 | DebugAnnotator *g_debugAnnotator = nullptr; |
| 34 | |
| 35 | std::mutex *g_debugMutex = nullptr; |
| 36 | |
| 37 | constexpr std::array<const char *, LOG_NUM_SEVERITIES> g_logSeverityNames = { |
| 38 | {"EVENT" , "WARN" , "ERR" , "FATAL" }}; |
| 39 | |
| 40 | constexpr const char *LogSeverityName(int severity) |
| 41 | { |
| 42 | return (severity >= 0 && severity < LOG_NUM_SEVERITIES) ? g_logSeverityNames[severity] |
| 43 | : "UNKNOWN" ; |
| 44 | } |
| 45 | |
| 46 | bool ShouldCreateLogMessage(LogSeverity severity) |
| 47 | { |
| 48 | #if defined(ANGLE_TRACE_ENABLED) |
| 49 | return true; |
| 50 | #elif defined(ANGLE_ENABLE_ASSERTS) |
| 51 | return severity != LOG_EVENT; |
| 52 | #else |
| 53 | return false; |
| 54 | #endif |
| 55 | } |
| 56 | |
| 57 | } // namespace |
| 58 | |
| 59 | namespace priv |
| 60 | { |
| 61 | |
| 62 | bool ShouldCreatePlatformLogMessage(LogSeverity severity) |
| 63 | { |
| 64 | #if defined(ANGLE_TRACE_ENABLED) |
| 65 | return true; |
| 66 | #else |
| 67 | return severity != LOG_EVENT; |
| 68 | #endif |
| 69 | } |
| 70 | |
| 71 | // This is never instantiated, it's just used for EAT_STREAM_PARAMETERS to an object of the correct |
| 72 | // type on the LHS of the unused part of the ternary operator. |
| 73 | std::ostream *gSwallowStream; |
| 74 | } // namespace priv |
| 75 | |
| 76 | bool DebugAnnotationsActive() |
| 77 | { |
| 78 | #if defined(ANGLE_ENABLE_DEBUG_ANNOTATIONS) |
| 79 | return g_debugAnnotator != nullptr && g_debugAnnotator->getStatus(); |
| 80 | #else |
| 81 | return false; |
| 82 | #endif |
| 83 | } |
| 84 | |
| 85 | bool DebugAnnotationsInitialized() |
| 86 | { |
| 87 | return g_debugAnnotator != nullptr; |
| 88 | } |
| 89 | |
| 90 | void InitializeDebugAnnotations(DebugAnnotator *debugAnnotator) |
| 91 | { |
| 92 | UninitializeDebugAnnotations(); |
| 93 | g_debugAnnotator = debugAnnotator; |
| 94 | } |
| 95 | |
| 96 | void UninitializeDebugAnnotations() |
| 97 | { |
| 98 | // Pointer is not managed. |
| 99 | g_debugAnnotator = nullptr; |
| 100 | } |
| 101 | |
| 102 | void InitializeDebugMutexIfNeeded() |
| 103 | { |
| 104 | if (g_debugMutex == nullptr) |
| 105 | { |
| 106 | g_debugMutex = new std::mutex(); |
| 107 | } |
| 108 | } |
| 109 | |
| 110 | ScopedPerfEventHelper::ScopedPerfEventHelper(const char *format, ...) : mFunctionName(nullptr) |
| 111 | { |
| 112 | bool dbgTrace = DebugAnnotationsActive(); |
| 113 | #if !defined(ANGLE_ENABLE_DEBUG_TRACE) |
| 114 | if (!dbgTrace) |
| 115 | { |
| 116 | return; |
| 117 | } |
| 118 | #endif // !ANGLE_ENABLE_DEBUG_TRACE |
| 119 | |
| 120 | va_list vararg; |
| 121 | va_start(vararg, format); |
| 122 | std::vector<char> buffer(512); |
| 123 | size_t len = FormatStringIntoVector(format, vararg, buffer); |
| 124 | ANGLE_LOG(EVENT) << std::string(&buffer[0], len); |
| 125 | // Pull function name from variable args |
| 126 | mFunctionName = va_arg(vararg, const char *); |
| 127 | va_end(vararg); |
| 128 | if (dbgTrace) |
| 129 | { |
| 130 | g_debugAnnotator->beginEvent(mFunctionName, buffer.data()); |
| 131 | } |
| 132 | } |
| 133 | |
| 134 | ScopedPerfEventHelper::~ScopedPerfEventHelper() |
| 135 | { |
| 136 | if (DebugAnnotationsActive()) |
| 137 | { |
| 138 | g_debugAnnotator->endEvent(mFunctionName); |
| 139 | } |
| 140 | } |
| 141 | |
| 142 | LogMessage::LogMessage(const char *function, int line, LogSeverity severity) |
| 143 | : mFunction(function), mLine(line), mSeverity(severity) |
| 144 | { |
| 145 | // EVENT() does not require additional function(line) info. |
| 146 | if (mSeverity != LOG_EVENT) |
| 147 | { |
| 148 | mStream << mFunction << "(" << mLine << "): " ; |
| 149 | } |
| 150 | } |
| 151 | |
| 152 | LogMessage::~LogMessage() |
| 153 | { |
| 154 | std::unique_lock<std::mutex> lock; |
| 155 | if (g_debugMutex != nullptr) |
| 156 | { |
| 157 | lock = std::unique_lock<std::mutex>(*g_debugMutex); |
| 158 | } |
| 159 | |
| 160 | if (DebugAnnotationsInitialized() && (mSeverity >= LOG_WARN)) |
| 161 | { |
| 162 | g_debugAnnotator->logMessage(*this); |
| 163 | } |
| 164 | else |
| 165 | { |
| 166 | Trace(getSeverity(), getMessage().c_str()); |
| 167 | } |
| 168 | |
| 169 | if (mSeverity == LOG_FATAL) |
| 170 | { |
| 171 | ANGLE_CRASH(); |
| 172 | } |
| 173 | } |
| 174 | |
| 175 | void Trace(LogSeverity severity, const char *message) |
| 176 | { |
| 177 | if (!ShouldCreateLogMessage(severity)) |
| 178 | { |
| 179 | return; |
| 180 | } |
| 181 | |
| 182 | std::string str(message); |
| 183 | |
| 184 | if (DebugAnnotationsActive()) |
| 185 | { |
| 186 | |
| 187 | switch (severity) |
| 188 | { |
| 189 | case LOG_EVENT: |
| 190 | // Debugging logging done in ScopedPerfEventHelper |
| 191 | break; |
| 192 | default: |
| 193 | g_debugAnnotator->setMarker(message); |
| 194 | break; |
| 195 | } |
| 196 | } |
| 197 | |
| 198 | if (severity == LOG_FATAL || severity == LOG_ERR || severity == LOG_WARN) |
| 199 | { |
| 200 | #if defined(ANGLE_PLATFORM_ANDROID) |
| 201 | android_LogPriority android_priority = ANDROID_LOG_ERROR; |
| 202 | switch (severity) |
| 203 | { |
| 204 | case LOG_WARN: |
| 205 | android_priority = ANDROID_LOG_WARN; |
| 206 | break; |
| 207 | case LOG_ERR: |
| 208 | android_priority = ANDROID_LOG_ERROR; |
| 209 | break; |
| 210 | case LOG_FATAL: |
| 211 | android_priority = ANDROID_LOG_FATAL; |
| 212 | break; |
| 213 | default: |
| 214 | UNREACHABLE(); |
| 215 | } |
| 216 | __android_log_print(android_priority, "ANGLE" , "%s: %s\n" , LogSeverityName(severity), |
| 217 | str.c_str()); |
| 218 | #else |
| 219 | // Note: we use fprintf because <iostream> includes static initializers. |
| 220 | fprintf((severity >= LOG_ERR) ? stderr : stdout, "%s: %s\n" , LogSeverityName(severity), |
| 221 | str.c_str()); |
| 222 | #endif |
| 223 | } |
| 224 | |
| 225 | #if defined(ANGLE_PLATFORM_WINDOWS) && \ |
| 226 | (defined(ANGLE_ENABLE_DEBUG_TRACE_TO_DEBUGGER) || !defined(NDEBUG)) |
| 227 | # if !defined(ANGLE_ENABLE_DEBUG_TRACE_TO_DEBUGGER) |
| 228 | if (severity >= LOG_ERR) |
| 229 | # endif // !defined(ANGLE_ENABLE_DEBUG_TRACE_TO_DEBUGGER) |
| 230 | { |
| 231 | OutputDebugStringA(str.c_str()); |
| 232 | } |
| 233 | #endif |
| 234 | |
| 235 | #if defined(ANGLE_ENABLE_DEBUG_TRACE) |
| 236 | # if defined(NDEBUG) |
| 237 | if (severity == LOG_EVENT || severity == LOG_WARN) |
| 238 | { |
| 239 | return; |
| 240 | } |
| 241 | # endif // defined(NDEBUG) |
| 242 | static std::ofstream file(TRACE_OUTPUT_FILE, std::ofstream::app); |
| 243 | if (file) |
| 244 | { |
| 245 | file << LogSeverityName(severity) << ": " << str << std::endl; |
| 246 | file.flush(); |
| 247 | } |
| 248 | #endif // defined(ANGLE_ENABLE_DEBUG_TRACE) |
| 249 | } |
| 250 | |
| 251 | LogSeverity LogMessage::getSeverity() const |
| 252 | { |
| 253 | return mSeverity; |
| 254 | } |
| 255 | |
| 256 | std::string LogMessage::getMessage() const |
| 257 | { |
| 258 | return mStream.str(); |
| 259 | } |
| 260 | |
| 261 | #if defined(ANGLE_PLATFORM_WINDOWS) |
| 262 | priv::FmtHexHelper<HRESULT> FmtHR(HRESULT value) |
| 263 | { |
| 264 | return priv::FmtHexHelper<HRESULT>("HRESULT: " , value); |
| 265 | } |
| 266 | |
| 267 | priv::FmtHexHelper<DWORD> FmtErr(DWORD value) |
| 268 | { |
| 269 | return priv::FmtHexHelper<DWORD>("error: " , value); |
| 270 | } |
| 271 | #endif // defined(ANGLE_PLATFORM_WINDOWS) |
| 272 | |
| 273 | } // namespace gl |
| 274 | |