// Copyright 2009 the V8 project authors. All rights reserved. // Redistribution and use in source and binary forms, with or without // modification, are permitted provided that the following conditions are // met: // // * Redistributions of source code must retain the above copyright // notice, this list of conditions and the following disclaimer. // * Redistributions in binary form must reproduce the above // copyright notice, this list of conditions and the following // disclaimer in the documentation and/or other materials provided // with the distribution. // * Neither the name of Google Inc. nor the names of its // contributors may be used to endorse or promote products derived // from this software without specific prior written permission. // // THIS SOFTWARE IS PROVIDED BY THE COPYRIGHT HOLDERS AND CONTRIBUTORS // "AS IS" AND ANY EXPRESS OR IMPLIED WARRANTIES, INCLUDING, BUT NOT // LIMITED TO, THE IMPLIED WARRANTIES OF MERCHANTABILITY AND FITNESS FOR // A PARTICULAR PURPOSE ARE DISCLAIMED. IN NO EVENT SHALL THE COPYRIGHT // OWNER OR CONTRIBUTORS BE LIABLE FOR ANY DIRECT, INDIRECT, INCIDENTAL, // SPECIAL, EXEMPLARY, OR CONSEQUENTIAL DAMAGES (INCLUDING, BUT NOT // LIMITED TO, PROCUREMENT OF SUBSTITUTE GOODS OR SERVICES; LOSS OF USE, // DATA, OR PROFITS; OR BUSINESS INTERRUPTION) HOWEVER CAUSED AND ON ANY // THEORY OF LIABILITY, WHETHER IN CONTRACT, STRICT LIABILITY, OR TORT // (INCLUDING NEGLIGENCE OR OTHERWISE) ARISING IN ANY WAY OUT OF THE USE // OF THIS SOFTWARE, EVEN IF ADVISED OF THE POSSIBILITY OF SUCH DAMAGE. #include "v8.h" #include "log-utils.h" #include "string-stream.h" namespace v8 { namespace internal { const char* const Log::kLogToTemporaryFile = "&"; Log::Log(Logger* logger) : is_stopped_(false), output_handle_(NULL), ll_output_handle_(NULL), mutex_(NULL), message_buffer_(NULL), logger_(logger) { } static void AddIsolateIdIfNeeded(StringStream* stream) { Isolate* isolate = Isolate::Current(); if (isolate->IsDefaultIsolate()) return; stream->Add("isolate-%p-", isolate); } void Log::Initialize() { mutex_ = OS::CreateMutex(); message_buffer_ = NewArray(kMessageBufferSize); // --log-all enables all the log flags. if (FLAG_log_all) { FLAG_log_runtime = true; FLAG_log_api = true; FLAG_log_code = true; FLAG_log_gc = true; FLAG_log_suspect = true; FLAG_log_handles = true; FLAG_log_regexp = true; FLAG_log_internal_timer_events = true; } // --prof implies --log-code. if (FLAG_prof) FLAG_log_code = true; // --prof_lazy controls --log-code, implies --noprof_auto. if (FLAG_prof_lazy) { FLAG_log_code = false; FLAG_prof_auto = false; } bool open_log_file = FLAG_log || FLAG_log_runtime || FLAG_log_api || FLAG_log_code || FLAG_log_gc || FLAG_log_handles || FLAG_log_suspect || FLAG_log_regexp || FLAG_log_state_changes || FLAG_ll_prof || FLAG_log_internal_timer_events; // If we're logging anything, we need to open the log file. if (open_log_file) { if (strcmp(FLAG_logfile, "-") == 0) { OpenStdout(); } else if (strcmp(FLAG_logfile, kLogToTemporaryFile) == 0) { OpenTemporaryFile(); } else { if (strchr(FLAG_logfile, '%') != NULL || !Isolate::Current()->IsDefaultIsolate()) { // If there's a '%' in the log file name we have to expand // placeholders. HeapStringAllocator allocator; StringStream stream(&allocator); AddIsolateIdIfNeeded(&stream); for (const char* p = FLAG_logfile; *p; p++) { if (*p == '%') { p++; switch (*p) { case '\0': // If there's a % at the end of the string we back up // one character so we can escape the loop properly. p--; break; case 'p': stream.Add("%d", OS::GetCurrentProcessId()); break; case 't': { // %t expands to the current time in milliseconds. double time = OS::TimeCurrentMillis(); stream.Add("%.0f", FmtElm(time)); break; } case '%': // %% expands (contracts really) to %. stream.Put('%'); break; default: // All other %'s expand to themselves. stream.Put('%'); stream.Put(*p); break; } } else { stream.Put(*p); } } SmartArrayPointer expanded = stream.ToCString(); OpenFile(*expanded); } else { OpenFile(FLAG_logfile); } } } } void Log::OpenStdout() { ASSERT(!IsEnabled()); output_handle_ = stdout; } void Log::OpenTemporaryFile() { ASSERT(!IsEnabled()); output_handle_ = i::OS::OpenTemporaryFile(); } // Extension added to V8 log file name to get the low-level log name. static const char kLowLevelLogExt[] = ".ll"; // File buffer size of the low-level log. We don't use the default to // minimize the associated overhead. static const int kLowLevelLogBufferSize = 2 * MB; void Log::OpenFile(const char* name) { ASSERT(!IsEnabled()); output_handle_ = OS::FOpen(name, OS::LogFileOpenMode); if (FLAG_ll_prof) { // Open the low-level log file. size_t len = strlen(name); ScopedVector ll_name(static_cast(len + sizeof(kLowLevelLogExt))); OS::MemCopy(ll_name.start(), name, len); OS::MemCopy(ll_name.start() + len, kLowLevelLogExt, sizeof(kLowLevelLogExt)); ll_output_handle_ = OS::FOpen(ll_name.start(), OS::LogFileOpenMode); setvbuf(ll_output_handle_, NULL, _IOFBF, kLowLevelLogBufferSize); } } FILE* Log::Close() { FILE* result = NULL; if (output_handle_ != NULL) { if (strcmp(FLAG_logfile, kLogToTemporaryFile) != 0) { fclose(output_handle_); } else { result = output_handle_; } } output_handle_ = NULL; if (ll_output_handle_ != NULL) fclose(ll_output_handle_); ll_output_handle_ = NULL; DeleteArray(message_buffer_); message_buffer_ = NULL; delete mutex_; mutex_ = NULL; is_stopped_ = false; return result; } LogMessageBuilder::LogMessageBuilder(Logger* logger) : log_(logger->log_), sl(log_->mutex_), pos_(0) { ASSERT(log_->message_buffer_ != NULL); } void LogMessageBuilder::Append(const char* format, ...) { Vector buf(log_->message_buffer_ + pos_, Log::kMessageBufferSize - pos_); va_list args; va_start(args, format); AppendVA(format, args); va_end(args); ASSERT(pos_ <= Log::kMessageBufferSize); } void LogMessageBuilder::AppendVA(const char* format, va_list args) { Vector buf(log_->message_buffer_ + pos_, Log::kMessageBufferSize - pos_); int result = v8::internal::OS::VSNPrintF(buf, format, args); // Result is -1 if output was truncated. if (result >= 0) { pos_ += result; } else { pos_ = Log::kMessageBufferSize; } ASSERT(pos_ <= Log::kMessageBufferSize); } void LogMessageBuilder::Append(const char c) { if (pos_ < Log::kMessageBufferSize) { log_->message_buffer_[pos_++] = c; } ASSERT(pos_ <= Log::kMessageBufferSize); } void LogMessageBuilder::Append(String* str) { AssertNoAllocation no_heap_allocation; // Ensure string stay valid. int length = str->length(); for (int i = 0; i < length; i++) { Append(static_cast(str->Get(i))); } } void LogMessageBuilder::AppendAddress(Address addr) { Append("0x%" V8PRIxPTR, addr); } void LogMessageBuilder::AppendDetailed(String* str, bool show_impl_info) { if (str == NULL) return; AssertNoAllocation no_heap_allocation; // Ensure string stay valid. int len = str->length(); if (len > 0x1000) len = 0x1000; if (show_impl_info) { Append(str->IsOneByteRepresentation() ? 'a' : '2'); if (StringShape(str).IsExternal()) Append('e'); if (StringShape(str).IsInternalized()) Append('#'); Append(":%i:", str->length()); } for (int i = 0; i < len; i++) { uc32 c = str->Get(i); if (c > 0xff) { Append("\\u%04x", c); } else if (c < 32 || c > 126) { Append("\\x%02x", c); } else if (c == ',') { Append("\\,"); } else if (c == '\\') { Append("\\\\"); } else if (c == '\"') { Append("\"\""); } else { Append("%lc", c); } } } void LogMessageBuilder::AppendStringPart(const char* str, int len) { if (pos_ + len > Log::kMessageBufferSize) { len = Log::kMessageBufferSize - pos_; ASSERT(len >= 0); if (len == 0) return; } Vector buf(log_->message_buffer_ + pos_, Log::kMessageBufferSize - pos_); OS::StrNCpy(buf, str, len); pos_ += len; ASSERT(pos_ <= Log::kMessageBufferSize); } void LogMessageBuilder::WriteToLogFile() { ASSERT(pos_ <= Log::kMessageBufferSize); const int written = log_->WriteToFile(log_->message_buffer_, pos_); if (written != pos_) { log_->stop(); log_->logger_->LogFailure(); } } } } // namespace v8::internal