X-Git-Url: https://git.cworth.org/git?a=blobdiff_plain;f=common%2Ftrace_writer_local.cpp;h=529f83b2fbd88b8f5406860d6dece3aef0d8b758;hb=62bce80269d08b94315ce5ca13ed0431137eeef9;hp=4d3949037951999563dd6271f7c5b918ab1d1e3b;hpb=7257d46c8a6938428750e9272dfb4ac47b9b4404;p=apitrace diff --git a/common/trace_writer_local.cpp b/common/trace_writer_local.cpp index 4d39490..529f83b 100644 --- a/common/trace_writer_local.cpp +++ b/common/trace_writer_local.cpp @@ -31,9 +31,12 @@ #include #include "os.hpp" +#include "os_thread.hpp" +#include "os_string.hpp" #include "trace_file.hpp" -#include "trace_writer.hpp" +#include "trace_writer_local.hpp" #include "trace_format.hpp" +#include "os_backtrace.hpp" namespace trace { @@ -61,6 +64,8 @@ static void exceptionCallback(void) LocalWriter::LocalWriter() : acquired(0) { + os::log("apitrace: loaded\n"); + // Install the signal handlers as early as possible, to prevent // interfering with the application's signal handling. os::setExceptionCallback(exceptionCallback); @@ -69,34 +74,42 @@ LocalWriter::LocalWriter() : LocalWriter::~LocalWriter() { os::resetExceptionCallback(); + checkProcessId(); } void LocalWriter::open(void) { + os::String szFileName; - static unsigned dwCounter = 0; - - const char *szExtension = "trace"; - char szFileName[PATH_MAX]; const char *lpFileName; lpFileName = getenv("TRACE_FILE"); - if (lpFileName) { - strncpy(szFileName, lpFileName, PATH_MAX); - } - else { - os::Path szProcessName = os::getProcessName(); - os::Path szCurrentDir = os::getCurrentDir(); + if (!lpFileName) { + static unsigned dwCounter = 0; + + os::String process = os::getProcessName(); +#ifdef _WIN32 + process.trimExtension(); +#endif + process.trimDirectory(); + +#ifdef ANDROID + os::String prefix = "/data"; +#else + os::String prefix = os::getCurrentDir(); +#endif + prefix.join(process); for (;;) { FILE *file; if (dwCounter) - snprintf(szFileName, PATH_MAX, "%s%c%s.%u.%s", szCurrentDir.str(), PATH_SEP, szProcessName.str(), dwCounter, szExtension); + szFileName = os::String::format("%s.%u.trace", prefix.str(), dwCounter); else - snprintf(szFileName, PATH_MAX, "%s%c%s.%s", szCurrentDir.str(), PATH_SEP, szProcessName.str(), szExtension); + szFileName = os::String::format("%s.trace", prefix.str()); - file = fopen(szFileName, "rb"); + lpFileName = szFileName; + file = fopen(lpFileName, "rb"); if (file == NULL) break; @@ -106,9 +119,14 @@ LocalWriter::open(void) { } } - os::log("apitrace: tracing to %s\n", szFileName); + os::log("apitrace: tracing to %s\n", lpFileName); + + if (!Writer::open(lpFileName)) { + os::log("apitrace: error: failed to open %s\n", lpFileName); + os::abort(); + } - Writer::open(szFileName); + pid = os::getCurrentProcessId(); #if 0 // For debugging the exception handler @@ -116,25 +134,64 @@ LocalWriter::open(void) { #endif } -unsigned LocalWriter::beginEnter(const FunctionSig *sig) { - os::acquireMutex(); +static uintptr_t next_thread_num = 1; + +static OS_THREAD_SPECIFIC_PTR(void) +thread_num; + +void LocalWriter::checkProcessId(void) { + if (m_file->isOpened() && + os::getCurrentProcessId() != pid) { + // We are a forked child process that inherited the trace file, so + // create a new file. We can't call any method of the current + // file, as it may cause it to flush and corrupt the parent's + // trace, so we effectively leak the old file object. + m_file = File::createSnappy(); + // Don't want to open the same file again + os::unsetEnvironment("TRACE_FILE"); + open(); + } +} + +unsigned LocalWriter::beginEnter(const FunctionSig *sig, bool fake) { + mutex.lock(); ++acquired; + checkProcessId(); if (!m_file->isOpened()) { open(); } - return Writer::beginEnter(sig); + // Although thread_num is a void *, we actually use it as a uintptr_t + uintptr_t this_thread_num = + reinterpret_cast(static_cast(thread_num)); + if (!this_thread_num) { + this_thread_num = next_thread_num++; + thread_num = reinterpret_cast(this_thread_num); + } + + assert(this_thread_num); + unsigned thread_id = this_thread_num - 1; + unsigned call_no = Writer::beginEnter(sig, thread_id); + if (!fake && os::backtrace_is_needed(sig->name)) { + std::vector backtrace = os::get_backtrace(); + beginBacktrace(backtrace.size()); + for (unsigned i = 0; i < backtrace.size(); ++i) { + writeStackFrame(&backtrace[i]); + } + endBacktrace(); + } + return call_no; } void LocalWriter::endEnter(void) { Writer::endEnter(); --acquired; - os::releaseMutex(); + mutex.unlock(); } void LocalWriter::beginLeave(unsigned call) { - os::acquireMutex(); + mutex.lock(); ++acquired; Writer::beginLeave(call); } @@ -142,23 +199,32 @@ void LocalWriter::beginLeave(unsigned call) { void LocalWriter::endLeave(void) { Writer::endLeave(); --acquired; - os::releaseMutex(); + mutex.unlock(); } void LocalWriter::flush(void) { /* * Do nothing if the mutex is already acquired (e.g., if a segfault happen - * while writing the file) to prevent dead-lock. + * while writing the file) as state could be inconsistent, therefore yield + * inconsistent trace files and/or repeated segfaults till infinity. */ - if (!acquired) { - os::acquireMutex(); + mutex.lock(); + if (acquired) { + os::log("apitrace: ignoring exception while tracing\n"); + } else { + ++acquired; if (m_file->isOpened()) { - os::log("apitrace: flushing trace due to an exception\n"); - m_file->flush(); + if (os::getCurrentProcessId() != pid) { + os::log("apitrace: ignoring exception in child process\n"); + } else { + os::log("apitrace: flushing trace due to an exception\n"); + m_file->flush(); + } } - os::releaseMutex(); + --acquired; } + mutex.unlock(); }