|
@@ -5,6 +5,8 @@
|
|
|
|
|
|
|
|
#include <anki/core/Trace.h>
|
|
#include <anki/core/Trace.h>
|
|
|
|
|
|
|
|
|
|
+#if ANKI_ENABLE_TRACE
|
|
|
|
|
+
|
|
|
namespace anki {
|
|
namespace anki {
|
|
|
|
|
|
|
|
//==============================================================================
|
|
//==============================================================================
|
|
@@ -25,14 +27,18 @@ static Array<const char*, U(TraceEventType::COUNT)> eventNames = {{
|
|
|
"RENDER_SM",
|
|
"RENDER_SM",
|
|
|
"RENDER_DRAWER",
|
|
"RENDER_DRAWER",
|
|
|
"GL_THREAD",
|
|
"GL_THREAD",
|
|
|
- "SWAP_BUFFERS",
|
|
|
|
|
- "IDLE"
|
|
|
|
|
|
|
+ "SWAP_BUFFERS"
|
|
|
}};
|
|
}};
|
|
|
|
|
|
|
|
static Array<const char*, U(TraceCounterType::COUNT)> counterNames = {{
|
|
static Array<const char*, U(TraceCounterType::COUNT)> counterNames = {{
|
|
|
"GR_DRAWCALLS",
|
|
"GR_DRAWCALLS",
|
|
|
- "GR_DYNAMIC_UNIFORMS_SIZE"
|
|
|
|
|
- "RENDERER_LIGHT_COUNT",
|
|
|
|
|
|
|
+ "GR_DYNAMIC_UNIFORMS_SIZE",
|
|
|
|
|
+ "GR_DYNAMIC_STORAGE_SIZE",
|
|
|
|
|
+ "GR_VERTICES",
|
|
|
|
|
+ "RENDERER_LIGHTS",
|
|
|
|
|
+ "RENDERER_SHADOW_PASSES",
|
|
|
|
|
+ "RENDERER_MERGED_DRAWCALLS",
|
|
|
|
|
+ "SCENE_NODES_UPDATED"
|
|
|
}};
|
|
}};
|
|
|
|
|
|
|
|
#define ANKI_TRACE_FILE_ERROR() \
|
|
#define ANKI_TRACE_FILE_ERROR() \
|
|
@@ -51,11 +57,11 @@ thread_local I g_traceEventsInFlight = 0;
|
|
|
//==============================================================================
|
|
//==============================================================================
|
|
|
TraceManager::~TraceManager()
|
|
TraceManager::~TraceManager()
|
|
|
{
|
|
{
|
|
|
- if(m_file.isOpen())
|
|
|
|
|
|
|
+ if(m_traceFile.isOpen())
|
|
|
{
|
|
{
|
|
|
- Error err = m_file.writeText("{\"name\": \"dummy\", \"cat\": \"PERF\", "
|
|
|
|
|
- "\"ph\": \"X\", \"pid\": 666, \"tid\": %llu, \"ts\": 0, "
|
|
|
|
|
- "\"dur\": 1}]}", Thread::getCurrentThreadId());
|
|
|
|
|
|
|
+ Error err = m_traceFile.writeText("{\"name\": \"dummy\", "
|
|
|
|
|
+ "\"cat\": \"PERF\", \"ph\": \"X\", \"pid\": 666, \"tid\": %llu, "
|
|
|
|
|
+ "\"ts\": 0, \"dur\": 1}]}", Thread::getCurrentThreadId());
|
|
|
|
|
|
|
|
ANKI_TRACE_FILE_ERROR();
|
|
ANKI_TRACE_FILE_ERROR();
|
|
|
}
|
|
}
|
|
@@ -64,16 +70,35 @@ TraceManager::~TraceManager()
|
|
|
//==============================================================================
|
|
//==============================================================================
|
|
|
Error TraceManager::create(HeapAllocator<U8> alloc, const CString& cacheDir)
|
|
Error TraceManager::create(HeapAllocator<U8> alloc, const CString& cacheDir)
|
|
|
{
|
|
{
|
|
|
- // Open and write some stuff to the file
|
|
|
|
|
|
|
+ // Create trace file
|
|
|
StringAuto fname(alloc);
|
|
StringAuto fname(alloc);
|
|
|
fname.sprintf("%s/trace.json", &cacheDir[0]);
|
|
fname.sprintf("%s/trace.json", &cacheDir[0]);
|
|
|
|
|
|
|
|
- ANKI_CHECK(m_file.open(fname.toCString(), File::OpenFlag::WRITE));
|
|
|
|
|
- ANKI_CHECK(m_file.writeText(
|
|
|
|
|
|
|
+ ANKI_CHECK(m_traceFile.open(fname.toCString(), File::OpenFlag::WRITE));
|
|
|
|
|
+ ANKI_CHECK(m_traceFile.writeText(
|
|
|
"{\n"
|
|
"{\n"
|
|
|
"\"displayTimeUnit\": \"ms\",\n"
|
|
"\"displayTimeUnit\": \"ms\",\n"
|
|
|
"\"traceEvents\": [\n"));
|
|
"\"traceEvents\": [\n"));
|
|
|
|
|
|
|
|
|
|
+ // Create per frame file
|
|
|
|
|
+ StringAuto perFrameFname(alloc);
|
|
|
|
|
+ perFrameFname.sprintf("%s/per_frame.csv", &cacheDir[0]);
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.open(perFrameFname.toCString(),
|
|
|
|
|
+ File::OpenFlag::WRITE));
|
|
|
|
|
+
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText("FPS, "));
|
|
|
|
|
+ for(U i = 0; i < U(TraceCounterType::COUNT); ++i)
|
|
|
|
|
+ {
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText("%s, ", counterNames[i]));
|
|
|
|
|
+ }
|
|
|
|
|
+
|
|
|
|
|
+ for(U i = 0; i < U(TraceEventType::COUNT); ++i)
|
|
|
|
|
+ {
|
|
|
|
|
+ const char* fmt =
|
|
|
|
|
+ (i < U(TraceEventType::COUNT) - 1) ? "%s, " : "%s\n";
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText(fmt, eventNames[i]));
|
|
|
|
|
+ }
|
|
|
|
|
+
|
|
|
return ErrorCode::NONE;
|
|
return ErrorCode::NONE;
|
|
|
}
|
|
}
|
|
|
|
|
|
|
@@ -82,7 +107,7 @@ void TraceManager::startEvent()
|
|
|
{
|
|
{
|
|
|
I i = ++g_traceEventsInFlight;
|
|
I i = ++g_traceEventsInFlight;
|
|
|
--i;
|
|
--i;
|
|
|
- ANKI_ASSERT(i > 0 && <= MAX_EVENTS_DEPTH);
|
|
|
|
|
|
|
+ ANKI_ASSERT(i >= 0 && i <= I(MAX_EVENTS_DEPTH));
|
|
|
|
|
|
|
|
g_traceEventStartTime[i] = HighRezTimer::getCurrentTime();
|
|
g_traceEventStartTime[i] = HighRezTimer::getCurrentTime();
|
|
|
}
|
|
}
|
|
@@ -91,56 +116,60 @@ void TraceManager::startEvent()
|
|
|
void TraceManager::stopEvent(TraceEventType type)
|
|
void TraceManager::stopEvent(TraceEventType type)
|
|
|
{
|
|
{
|
|
|
I i = --g_traceEventsInFlight;
|
|
I i = --g_traceEventsInFlight;
|
|
|
- ANKI_ASSERT(i >= 0 && < MAX_EVENTS_DEPTH);
|
|
|
|
|
|
|
+ ANKI_ASSERT(i >= 0 && i < I(MAX_EVENTS_DEPTH));
|
|
|
auto startedTime = g_traceEventStartTime[i];
|
|
auto startedTime = g_traceEventStartTime[i];
|
|
|
|
|
|
|
|
U id = m_count.fetchAdd(1);
|
|
U id = m_count.fetchAdd(1);
|
|
|
if(id < BUFFERED_ENTRIES)
|
|
if(id < BUFFERED_ENTRIES)
|
|
|
{
|
|
{
|
|
|
auto now = HighRezTimer::getCurrentTime();
|
|
auto now = HighRezTimer::getCurrentTime();
|
|
|
- m_entries[id] = Entry{type, startedTime, now - startedTime,
|
|
|
|
|
|
|
+ auto dur = now - startedTime;
|
|
|
|
|
+
|
|
|
|
|
+ m_entries[id] = Entry{type, startedTime, dur,
|
|
|
Thread::getCurrentThreadId()};
|
|
Thread::getCurrentThreadId()};
|
|
|
|
|
+
|
|
|
|
|
+ m_perFrameCounters[U(TraceCounterType::COUNT) + U(type)].fetchAdd(
|
|
|
|
|
+ U64(dur * 1000000000.0));
|
|
|
}
|
|
}
|
|
|
else
|
|
else
|
|
|
{
|
|
{
|
|
|
ANKI_LOGW("Increase the buffered trace entries");
|
|
ANKI_LOGW("Increase the buffered trace entries");
|
|
|
|
|
+ m_perFrameCounters[U(TraceCounterType::COUNT) + U(type)].fetchAdd(0);
|
|
|
}
|
|
}
|
|
|
}
|
|
}
|
|
|
|
|
|
|
|
//==============================================================================
|
|
//==============================================================================
|
|
|
-void TraceManager::flushCounters()
|
|
|
|
|
|
|
+Error TraceManager::flushCounters()
|
|
|
{
|
|
{
|
|
|
|
|
+ // Write the FPS counter
|
|
|
|
|
+ HighRezTimer::Scalar now = HighRezTimer::getCurrentTime();
|
|
|
|
|
+ HighRezTimer::Scalar time = now - m_startFrameTime;
|
|
|
|
|
+ F32 fps = 1.0 / time;
|
|
|
|
|
+ ANKI_CHECK(m_traceFile.writeText(
|
|
|
|
|
+ "{\"name\": \"FPS\", \"cat\": \"PERF\", \"ph\": \"C\", "
|
|
|
|
|
+ "\"pid\": 666, \"ts\": %llu, \"args\": {\"val\": %f}},\n",
|
|
|
|
|
+ U64(m_startFrameTime * 1000000.0), fps));
|
|
|
|
|
+
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText("%f, ", fps));
|
|
|
|
|
+
|
|
|
for(U i = 0; i < U(TraceCounterType::COUNT); ++i)
|
|
for(U i = 0; i < U(TraceCounterType::COUNT); ++i)
|
|
|
{
|
|
{
|
|
|
- auto count =
|
|
|
|
|
- m_perFrameCounters[U(TraceEventType::COUNT) + i].exchange(0);
|
|
|
|
|
|
|
+ auto count = m_perFrameCounters[i].exchange(0);
|
|
|
|
|
|
|
|
-
|
|
|
|
|
- Error err = m_file.writeText(
|
|
|
|
|
|
|
+ ANKI_CHECK(m_traceFile.writeText(
|
|
|
"{\"name\": \"%s\", \"cat\": \"PERF\", \"ph\": \"C\", "
|
|
"{\"name\": \"%s\", \"cat\": \"PERF\", \"ph\": \"C\", "
|
|
|
"\"pid\": 666, \"ts\": %llu, \"args\": {\"val\": %llu}},\n",
|
|
"\"pid\": 666, \"ts\": %llu, \"args\": {\"val\": %llu}},\n",
|
|
|
- counterNames[i], U64(m_startFrameTime * 1000000.0), count);
|
|
|
|
|
|
|
+ counterNames[i], U64(m_startFrameTime * 1000000.0), count));
|
|
|
|
|
|
|
|
- ANKI_TRACE_FILE_ERROR();
|
|
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText("%llu, ", count));
|
|
|
}
|
|
}
|
|
|
|
|
+
|
|
|
|
|
+ return ErrorCode::NONE;
|
|
|
}
|
|
}
|
|
|
|
|
|
|
|
//==============================================================================
|
|
//==============================================================================
|
|
|
-void TraceManager::stopFrame()
|
|
|
|
|
|
|
+Error TraceManager::flushEvents()
|
|
|
{
|
|
{
|
|
|
- flushCounters();
|
|
|
|
|
-
|
|
|
|
|
- // Write the FPS counter
|
|
|
|
|
- HighRezTimer::Scalar now = HighRezTimer::getCurrentTime();
|
|
|
|
|
- HighRezTimer::Scalar time = now - m_startFrameTime;
|
|
|
|
|
- F32 fps = 1.0 / time;
|
|
|
|
|
- Error err = m_file.writeText(
|
|
|
|
|
- "{\"name\": \"FPS\", \"cat\": \"PERF\", \"ph\": \"C\", "
|
|
|
|
|
- "\"pid\": 666, \"ts\": %llu, \"args\": {\"val\": %f}},\n",
|
|
|
|
|
- U64(m_startFrameTime * 1000000.0), fps);
|
|
|
|
|
-
|
|
|
|
|
- ANKI_TRACE_FILE_ERROR();
|
|
|
|
|
-
|
|
|
|
|
// Write the events
|
|
// Write the events
|
|
|
U count = m_count.exchange(0);
|
|
U count = m_count.exchange(0);
|
|
|
count = min<U>(count, BUFFERED_ENTRIES);
|
|
count = min<U>(count, BUFFERED_ENTRIES);
|
|
@@ -149,14 +178,41 @@ void TraceManager::stopFrame()
|
|
|
{
|
|
{
|
|
|
const Entry& e = m_entries[i];
|
|
const Entry& e = m_entries[i];
|
|
|
|
|
|
|
|
- Error err = m_file.writeText(
|
|
|
|
|
|
|
+ ANKI_CHECK(m_traceFile.writeText(
|
|
|
"{\"name\": \"%s\", \"cat\": \"PERF\", \"ph\": \"X\", "
|
|
"{\"name\": \"%s\", \"cat\": \"PERF\", \"ph\": \"X\", "
|
|
|
"\"pid\": 666, \"tid\": %llu, \"ts\": %llu, \"dur\": %llu},\n",
|
|
"\"pid\": 666, \"tid\": %llu, \"ts\": %llu, \"dur\": %llu},\n",
|
|
|
eventNames[e.m_event], e.m_tid, U64(e.m_timestamp * 1000000.0),
|
|
eventNames[e.m_event], e.m_tid, U64(e.m_timestamp * 1000000.0),
|
|
|
- U64(e.m_duration * 1000000.0));
|
|
|
|
|
|
|
+ U64(e.m_duration * 1000000.0)));
|
|
|
|
|
+ }
|
|
|
|
|
|
|
|
- ANKI_TRACE_FILE_ERROR();
|
|
|
|
|
|
|
+ for(U i = 0; i < U(TraceEventType::COUNT); ++i)
|
|
|
|
|
+ {
|
|
|
|
|
+ const char* fmt =
|
|
|
|
|
+ (i < U(TraceEventType::COUNT) - 1) ? "%f, " : "%f\n";
|
|
|
|
|
+ U64 ns = m_perFrameCounters[
|
|
|
|
|
+ i + U(TraceCounterType::COUNT)].exchange(0);
|
|
|
|
|
+ ANKI_CHECK(m_perFrameFile.writeText(fmt, F64(ns) / 1000000.0));
|
|
|
|
|
+ }
|
|
|
|
|
+
|
|
|
|
|
+ return ErrorCode::NONE;
|
|
|
|
|
+}
|
|
|
|
|
+
|
|
|
|
|
+//==============================================================================
|
|
|
|
|
+void TraceManager::stopFrame()
|
|
|
|
|
+{
|
|
|
|
|
+ Error err = flushCounters();
|
|
|
|
|
+
|
|
|
|
|
+ if(!err)
|
|
|
|
|
+ {
|
|
|
|
|
+ err = flushEvents();
|
|
|
|
|
+ }
|
|
|
|
|
+
|
|
|
|
|
+ if(err)
|
|
|
|
|
+ {
|
|
|
|
|
+ ANKI_LOGE("Error writing the trace file");
|
|
|
}
|
|
}
|
|
|
}
|
|
}
|
|
|
|
|
|
|
|
} // end namespace anki
|
|
} // end namespace anki
|
|
|
|
|
+
|
|
|
|
|
+#endif
|