// Copyright 2016 the V8 project authors. All rights reserved. // Use of this source code is governed by a BSD-style license that can be // found in the LICENSE file. #include #include "include/libplatform/v8-tracing.h" #include "src/tracing/trace-event.h" #include "test/cctest/cctest.h" namespace v8 { namespace platform { namespace tracing { TEST(TestTraceConfig) { LocalContext env; TraceConfig* trace_config = new TraceConfig(); trace_config->AddIncludedCategory("v8"); trace_config->AddIncludedCategory(TRACE_DISABLED_BY_DEFAULT("v8.runtime")); CHECK_EQ(trace_config->IsSystraceEnabled(), false); CHECK_EQ(trace_config->IsArgumentFilterEnabled(), false); CHECK_EQ(trace_config->IsCategoryGroupEnabled("v8"), true); CHECK_EQ(trace_config->IsCategoryGroupEnabled("v8.cpu_profile"), false); CHECK_EQ(trace_config->IsCategoryGroupEnabled("v8.cpu_profile.hires"), false); CHECK_EQ(trace_config->IsCategoryGroupEnabled( TRACE_DISABLED_BY_DEFAULT("v8.runtime")), true); CHECK_EQ(trace_config->IsCategoryGroupEnabled("v8,v8.cpu_profile"), true); CHECK_EQ( trace_config->IsCategoryGroupEnabled("v8,disabled-by-default-v8.runtime"), true); CHECK_EQ(trace_config->IsCategoryGroupEnabled( "v8_cpu_profile,v8.cpu_profile.hires"), false); delete trace_config; } TEST(TestTraceObject) { TraceObject trace_object; uint8_t category_enabled_flag = 41; trace_object.Initialize('X', &category_enabled_flag, "Test.Trace", "Test.Scope", 42, 123, 0, nullptr, nullptr, nullptr, nullptr, 0); CHECK_EQ('X', trace_object.phase()); CHECK_EQ(category_enabled_flag, *trace_object.category_enabled_flag()); CHECK_EQ(std::string("Test.Trace"), std::string(trace_object.name())); CHECK_EQ(std::string("Test.Scope"), std::string(trace_object.scope())); CHECK_EQ(0u, trace_object.duration()); CHECK_EQ(0u, trace_object.cpu_duration()); } class ConvertableToTraceFormatMock : public v8::ConvertableToTraceFormat { public: explicit ConvertableToTraceFormatMock(int value) : value_(value) {} void AppendAsTraceFormat(std::string* out) const override { *out += "[" + std::to_string(value_) + "," + std::to_string(value_) + "]"; } private: int value_; DISALLOW_COPY_AND_ASSIGN(ConvertableToTraceFormatMock); }; class MockTraceWriter : public TraceWriter { public: void AppendTraceEvent(TraceObject* trace_event) override { events_.push_back(trace_event->name()); } void Flush() override {} std::vector events() { return events_; } private: std::vector events_; }; TEST(TestTraceBufferRingBuffer) { // We should be able to add kChunkSize * 2 + 1 trace events. const int HANDLES_COUNT = TraceBufferChunk::kChunkSize * 2 + 1; MockTraceWriter* writer = new MockTraceWriter(); TraceBuffer* ring_buffer = TraceBuffer::CreateTraceBufferRingBuffer(2, writer); std::string names[HANDLES_COUNT]; for (int i = 0; i < HANDLES_COUNT; ++i) { names[i] = "Test.EventNo" + std::to_string(i); } std::vector handles(HANDLES_COUNT); uint8_t category_enabled_flag = 41; for (size_t i = 0; i < handles.size(); ++i) { TraceObject* trace_object = ring_buffer->AddTraceEvent(&handles[i]); CHECK_NOT_NULL(trace_object); trace_object->Initialize('X', &category_enabled_flag, names[i].c_str(), "Test.Scope", 42, 123, 0, nullptr, nullptr, nullptr, nullptr, 0); trace_object = ring_buffer->GetEventByHandle(handles[i]); CHECK_NOT_NULL(trace_object); CHECK_EQ('X', trace_object->phase()); CHECK_EQ(names[i], std::string(trace_object->name())); CHECK_EQ(category_enabled_flag, *trace_object->category_enabled_flag()); } // We should only be able to retrieve the last kChunkSize + 1. for (size_t i = 0; i < TraceBufferChunk::kChunkSize; ++i) { CHECK_NULL(ring_buffer->GetEventByHandle(handles[i])); } for (size_t i = TraceBufferChunk::kChunkSize; i < handles.size(); ++i) { TraceObject* trace_object = ring_buffer->GetEventByHandle(handles[i]); CHECK_NOT_NULL(trace_object); // The object properties should be correct. CHECK_EQ('X', trace_object->phase()); CHECK_EQ(names[i], std::string(trace_object->name())); CHECK_EQ(category_enabled_flag, *trace_object->category_enabled_flag()); } // Check Flush(), that the writer wrote the last kChunkSize 1 event names. ring_buffer->Flush(); auto events = writer->events(); CHECK_EQ(TraceBufferChunk::kChunkSize + 1, events.size()); for (size_t i = TraceBufferChunk::kChunkSize; i < handles.size(); ++i) { CHECK_EQ(names[i], events[i - TraceBufferChunk::kChunkSize]); } delete ring_buffer; } TEST(TestJSONTraceWriter) { std::ostringstream stream; v8::Platform* old_platform = i::V8::GetCurrentPlatform(); v8::Platform* default_platform = v8::platform::CreateDefaultPlatform(); i::V8::SetPlatformForTesting(default_platform); // Create a scope for the tracing controller to terminate the trace writer. { TracingController tracing_controller; platform::SetTracingController(default_platform, &tracing_controller); TraceWriter* writer = TraceWriter::CreateJSONTraceWriter(stream); TraceBuffer* ring_buffer = TraceBuffer::CreateTraceBufferRingBuffer(1, writer); tracing_controller.Initialize(ring_buffer); TraceConfig* trace_config = new TraceConfig(); trace_config->AddIncludedCategory("v8-cat"); tracing_controller.StartTracing(trace_config); TraceObject trace_object; trace_object.InitializeForTesting( 'X', tracing_controller.GetCategoryGroupEnabled("v8-cat"), "Test0", v8::internal::tracing::kGlobalScope, 42, 123, 0, nullptr, nullptr, nullptr, nullptr, TRACE_EVENT_FLAG_HAS_ID, 11, 22, 100, 50, 33, 44); writer->AppendTraceEvent(&trace_object); trace_object.InitializeForTesting( 'Y', tracing_controller.GetCategoryGroupEnabled("v8-cat"), "Test1", v8::internal::tracing::kGlobalScope, 43, 456, 0, nullptr, nullptr, nullptr, nullptr, 0, 55, 66, 110, 55, 77, 88); writer->AppendTraceEvent(&trace_object); tracing_controller.StopTracing(); } std::string trace_str = stream.str(); std::string expected_trace_str = "{\"traceEvents\":[{\"pid\":11,\"tid\":22,\"ts\":100,\"tts\":50," "\"ph\":\"X\",\"cat\":\"v8-cat\",\"name\":\"Test0\",\"dur\":33," "\"tdur\":44,\"id\":\"0x2a\",\"args\":{}},{\"pid\":55,\"tid\":66," "\"ts\":110,\"tts\":55,\"ph\":\"Y\",\"cat\":\"v8-cat\",\"name\":" "\"Test1\",\"dur\":77,\"tdur\":88,\"args\":{}}]}"; CHECK_EQ(expected_trace_str, trace_str); i::V8::SetPlatformForTesting(old_platform); } TEST(TestTracingController) { v8::Platform* old_platform = i::V8::GetCurrentPlatform(); v8::Platform* default_platform = v8::platform::CreateDefaultPlatform(); i::V8::SetPlatformForTesting(default_platform); TracingController tracing_controller; platform::SetTracingController(default_platform, &tracing_controller); MockTraceWriter* writer = new MockTraceWriter(); TraceBuffer* ring_buffer = TraceBuffer::CreateTraceBufferRingBuffer(1, writer); tracing_controller.Initialize(ring_buffer); TraceConfig* trace_config = new TraceConfig(); trace_config->AddIncludedCategory("v8"); tracing_controller.StartTracing(trace_config); TRACE_EVENT0("v8", "v8.Test"); // cat category is not included in default config TRACE_EVENT0("cat", "v8.Test2"); TRACE_EVENT0("v8", "v8.Test3"); tracing_controller.StopTracing(); CHECK_EQ(2u, writer->events().size()); CHECK_EQ(std::string("v8.Test"), writer->events()[0]); CHECK_EQ(std::string("v8.Test3"), writer->events()[1]); i::V8::SetPlatformForTesting(old_platform); } void GetJSONStrings(std::vector& ret, std::string str, std::string param, std::string start_delim, std::string end_delim) { size_t pos = str.find(param); while (pos != std::string::npos) { size_t start_pos = str.find(start_delim, pos + param.length()); size_t end_pos = str.find(end_delim, start_pos + 1); CHECK_NE(start_pos, std::string::npos); CHECK_NE(end_pos, std::string::npos); ret.push_back(str.substr(start_pos + 1, end_pos - start_pos - 1)); pos = str.find(param, pos + 1); } } TEST(TestTracingControllerMultipleArgsAndCopy) { std::ostringstream stream; v8::Platform* old_platform = i::V8::GetCurrentPlatform(); v8::Platform* default_platform = v8::platform::CreateDefaultPlatform(); i::V8::SetPlatformForTesting(default_platform); uint64_t aa = 11; unsigned int bb = 22; uint16_t cc = 33; unsigned char dd = 44; int64_t ee = -55; int ff = -66; int16_t gg = -77; signed char hh = -88; bool ii1 = true; bool ii2 = false; double jj1 = 99.0; double jj2 = 1e100; double jj3 = std::numeric_limits::quiet_NaN(); double jj4 = std::numeric_limits::infinity(); double jj5 = -std::numeric_limits::infinity(); void* kk = &aa; const char* ll = "100"; std::string mm = "INIT"; std::string mmm = "\"INIT\""; // Create a scope for the tracing controller to terminate the trace writer. { TracingController tracing_controller; platform::SetTracingController(default_platform, &tracing_controller); TraceWriter* writer = TraceWriter::CreateJSONTraceWriter(stream); TraceBuffer* ring_buffer = TraceBuffer::CreateTraceBufferRingBuffer(1, writer); tracing_controller.Initialize(ring_buffer); TraceConfig* trace_config = new TraceConfig(); trace_config->AddIncludedCategory("v8"); tracing_controller.StartTracing(trace_config); TRACE_EVENT1("v8", "v8.Test.aa", "aa", aa); TRACE_EVENT1("v8", "v8.Test.bb", "bb", bb); TRACE_EVENT1("v8", "v8.Test.cc", "cc", cc); TRACE_EVENT1("v8", "v8.Test.dd", "dd", dd); TRACE_EVENT1("v8", "v8.Test.ee", "ee", ee); TRACE_EVENT1("v8", "v8.Test.ff", "ff", ff); TRACE_EVENT1("v8", "v8.Test.gg", "gg", gg); TRACE_EVENT1("v8", "v8.Test.hh", "hh", hh); TRACE_EVENT1("v8", "v8.Test.ii", "ii1", ii1); TRACE_EVENT1("v8", "v8.Test.ii", "ii2", ii2); TRACE_EVENT1("v8", "v8.Test.jj1", "jj1", jj1); TRACE_EVENT1("v8", "v8.Test.jj2", "jj2", jj2); TRACE_EVENT1("v8", "v8.Test.jj3", "jj3", jj3); TRACE_EVENT1("v8", "v8.Test.jj4", "jj4", jj4); TRACE_EVENT1("v8", "v8.Test.jj5", "jj5", jj5); TRACE_EVENT1("v8", "v8.Test.kk", "kk", kk); TRACE_EVENT1("v8", "v8.Test.ll", "ll", ll); TRACE_EVENT1("v8", "v8.Test.mm", "mm", TRACE_STR_COPY(mmm.c_str())); TRACE_EVENT2("v8", "v8.Test2.1", "aa", aa, "ll", ll); TRACE_EVENT2("v8", "v8.Test2.2", "mm1", TRACE_STR_COPY(mm.c_str()), "mm2", TRACE_STR_COPY(mmm.c_str())); // Check copies are correct. TRACE_EVENT_COPY_INSTANT0("v8", mm.c_str(), TRACE_EVENT_SCOPE_THREAD); TRACE_EVENT_COPY_INSTANT2("v8", mm.c_str(), TRACE_EVENT_SCOPE_THREAD, "mm1", mm.c_str(), "mm2", mmm.c_str()); mm = "CHANGED"; mmm = "CHANGED"; TRACE_EVENT_INSTANT1("v8", "v8.Test", TRACE_EVENT_SCOPE_THREAD, "a1", new ConvertableToTraceFormatMock(42)); std::unique_ptr trace_event_arg( new ConvertableToTraceFormatMock(42)); TRACE_EVENT_INSTANT2("v8", "v8.Test", TRACE_EVENT_SCOPE_THREAD, "a1", std::move(trace_event_arg), "a2", new ConvertableToTraceFormatMock(123)); tracing_controller.StopTracing(); } std::string trace_str = stream.str(); std::vector all_args, all_names, all_cats; GetJSONStrings(all_args, trace_str, "\"args\"", "{", "}"); GetJSONStrings(all_names, trace_str, "\"name\"", "\"", "\""); GetJSONStrings(all_cats, trace_str, "\"cat\"", "\"", "\""); CHECK_EQ(all_args.size(), 24u); CHECK_EQ(all_args[0], "\"aa\":11"); CHECK_EQ(all_args[1], "\"bb\":22"); CHECK_EQ(all_args[2], "\"cc\":33"); CHECK_EQ(all_args[3], "\"dd\":44"); CHECK_EQ(all_args[4], "\"ee\":-55"); CHECK_EQ(all_args[5], "\"ff\":-66"); CHECK_EQ(all_args[6], "\"gg\":-77"); CHECK_EQ(all_args[7], "\"hh\":-88"); CHECK_EQ(all_args[8], "\"ii1\":true"); CHECK_EQ(all_args[9], "\"ii2\":false"); CHECK_EQ(all_args[10], "\"jj1\":99.0"); CHECK_EQ(all_args[11], "\"jj2\":1e+100"); CHECK_EQ(all_args[12], "\"jj3\":\"NaN\""); CHECK_EQ(all_args[13], "\"jj4\":\"Infinity\""); CHECK_EQ(all_args[14], "\"jj5\":\"-Infinity\""); std::ostringstream pointer_stream; pointer_stream << "\"kk\":\"" << &aa << "\""; CHECK_EQ(all_args[15], pointer_stream.str()); CHECK_EQ(all_args[16], "\"ll\":\"100\""); CHECK_EQ(all_args[17], "\"mm\":\"\\\"INIT\\\"\""); CHECK_EQ(all_names[18], "v8.Test2.1"); CHECK_EQ(all_args[18], "\"aa\":11,\"ll\":\"100\""); CHECK_EQ(all_args[19], "\"mm1\":\"INIT\",\"mm2\":\"\\\"INIT\\\"\""); CHECK_EQ(all_names[20], "INIT"); CHECK_EQ(all_names[21], "INIT"); CHECK_EQ(all_args[21], "\"mm1\":\"INIT\",\"mm2\":\"\\\"INIT\\\"\""); CHECK_EQ(all_args[22], "\"a1\":[42,42]"); CHECK_EQ(all_args[23], "\"a1\":[42,42],\"a2\":[123,123]"); i::V8::SetPlatformForTesting(old_platform); } namespace { class TraceStateObserverImpl : public Platform::TraceStateObserver { public: void OnTraceEnabled() override { ++enabled_count; } void OnTraceDisabled() override { ++disabled_count; } int enabled_count = 0; int disabled_count = 0; }; } // namespace TEST(TracingObservers) { v8::Platform* old_platform = i::V8::GetCurrentPlatform(); v8::Platform* default_platform = v8::platform::CreateDefaultPlatform(); i::V8::SetPlatformForTesting(default_platform); v8::platform::tracing::TracingController tracing_controller; v8::platform::SetTracingController(default_platform, &tracing_controller); MockTraceWriter* writer = new MockTraceWriter(); v8::platform::tracing::TraceBuffer* ring_buffer = v8::platform::tracing::TraceBuffer::CreateTraceBufferRingBuffer(1, writer); tracing_controller.Initialize(ring_buffer); v8::platform::tracing::TraceConfig* trace_config = new v8::platform::tracing::TraceConfig(); trace_config->AddIncludedCategory("v8"); TraceStateObserverImpl observer; default_platform->AddTraceStateObserver(&observer); CHECK_EQ(0, observer.enabled_count); CHECK_EQ(0, observer.disabled_count); tracing_controller.StartTracing(trace_config); CHECK_EQ(1, observer.enabled_count); CHECK_EQ(0, observer.disabled_count); TraceStateObserverImpl observer2; default_platform->AddTraceStateObserver(&observer2); CHECK_EQ(1, observer2.enabled_count); CHECK_EQ(0, observer2.disabled_count); default_platform->RemoveTraceStateObserver(&observer2); CHECK_EQ(1, observer2.enabled_count); CHECK_EQ(0, observer2.disabled_count); tracing_controller.StopTracing(); CHECK_EQ(1, observer.enabled_count); CHECK_EQ(1, observer.disabled_count); CHECK_EQ(1, observer2.enabled_count); CHECK_EQ(0, observer2.disabled_count); default_platform->RemoveTraceStateObserver(&observer); CHECK_EQ(1, observer.enabled_count); CHECK_EQ(1, observer.disabled_count); trace_config = new v8::platform::tracing::TraceConfig(); tracing_controller.StartTracing(trace_config); tracing_controller.StopTracing(); CHECK_EQ(1, observer.enabled_count); CHECK_EQ(1, observer.disabled_count); i::V8::SetPlatformForTesting(old_platform); } } // namespace tracing } // namespace platform } // namespace v8