forked from AuroraMiddleware/gtk
cc643df88b
usec is the scale of the monotonic timer which is where we get almost all the times from. The only actual source of nsec is the opengl GPU time (but who knows what the actual resulution of that is). Changing this to usec allows us to get rid of " * 1000" in a *lot* of places all over the codebase, which are ugly and confusing.
528 lines
13 KiB
C
528 lines
13 KiB
C
#include "config.h"
|
|
|
|
#include "gskprofilerprivate.h"
|
|
|
|
#define MAX_SAMPLES 32
|
|
|
|
typedef struct {
|
|
GQuark id;
|
|
char *description;
|
|
gint64 value;
|
|
gint64 n_samples;
|
|
gboolean can_reset : 1;
|
|
} NamedCounter;
|
|
|
|
typedef struct {
|
|
GQuark id;
|
|
char *description;
|
|
gint64 value;
|
|
gint64 start_time;
|
|
gint64 min_value;
|
|
gint64 max_value;
|
|
gint64 avg_value;
|
|
gint64 n_samples;
|
|
gboolean in_flight : 1;
|
|
gboolean can_reset : 1;
|
|
gboolean invert : 1;
|
|
} NamedTimer;
|
|
|
|
typedef struct {
|
|
GQuark id;
|
|
gint64 value;
|
|
} Sample;
|
|
|
|
struct _GskProfiler
|
|
{
|
|
GObject parent_instance;
|
|
|
|
GHashTable *counters;
|
|
GHashTable *timers;
|
|
|
|
Sample timer_samples[MAX_SAMPLES];
|
|
guint last_sample;
|
|
};
|
|
|
|
G_DEFINE_TYPE (GskProfiler, gsk_profiler, G_TYPE_OBJECT)
|
|
|
|
static void
|
|
named_counter_free (gpointer data)
|
|
{
|
|
NamedCounter *counter = data;
|
|
|
|
if (data == NULL)
|
|
return;
|
|
|
|
g_free (counter->description);
|
|
|
|
g_slice_free (NamedCounter, counter);
|
|
}
|
|
|
|
static void
|
|
named_timer_free (gpointer data)
|
|
{
|
|
NamedTimer *timer = data;
|
|
|
|
if (data == NULL)
|
|
return;
|
|
|
|
g_free (timer->description);
|
|
|
|
g_slice_free (NamedTimer, timer);
|
|
}
|
|
|
|
static void
|
|
gsk_profiler_finalize (GObject *gobject)
|
|
{
|
|
GskProfiler *self = GSK_PROFILER (gobject);
|
|
|
|
g_clear_pointer (&self->counters, g_hash_table_unref);
|
|
g_clear_pointer (&self->timers, g_hash_table_unref);
|
|
|
|
G_OBJECT_CLASS (gsk_profiler_parent_class)->finalize (gobject);
|
|
}
|
|
|
|
static void
|
|
gsk_profiler_class_init (GskProfilerClass *klass)
|
|
{
|
|
G_OBJECT_CLASS (klass)->finalize = gsk_profiler_finalize;
|
|
}
|
|
|
|
static void
|
|
gsk_profiler_init (GskProfiler *self)
|
|
{
|
|
self->counters = g_hash_table_new_full (g_direct_hash, g_direct_equal,
|
|
NULL,
|
|
named_counter_free);
|
|
self->timers = g_hash_table_new_full (g_direct_hash, g_direct_equal,
|
|
NULL,
|
|
named_timer_free);
|
|
}
|
|
|
|
GskProfiler *
|
|
gsk_profiler_new (void)
|
|
{
|
|
return g_object_new (GSK_TYPE_PROFILER, NULL);
|
|
}
|
|
|
|
static NamedCounter *
|
|
named_counter_new (GQuark id,
|
|
const char *description,
|
|
gboolean can_reset)
|
|
{
|
|
NamedCounter *res = g_slice_new0 (NamedCounter);
|
|
|
|
res->id = id;
|
|
res->description = g_strdup (description);
|
|
res->can_reset = can_reset;
|
|
|
|
return res;
|
|
}
|
|
|
|
static NamedCounter *
|
|
gsk_profiler_get_counter (GskProfiler *profiler,
|
|
GQuark id)
|
|
{
|
|
return g_hash_table_lookup (profiler->counters, GINT_TO_POINTER (id));
|
|
}
|
|
|
|
GQuark
|
|
gsk_profiler_add_counter (GskProfiler *profiler,
|
|
const char *counter_name,
|
|
const char *description,
|
|
gboolean can_reset)
|
|
{
|
|
NamedCounter *counter;
|
|
GQuark id;
|
|
|
|
g_return_val_if_fail (GSK_IS_PROFILER (profiler), 0);
|
|
|
|
id = g_quark_from_string (counter_name);
|
|
counter = gsk_profiler_get_counter (profiler, id);
|
|
if (counter != NULL)
|
|
{
|
|
g_critical ("Cannot add a counter '%s' as one already exists.", counter_name);
|
|
return counter->id;
|
|
}
|
|
|
|
counter = named_counter_new (id, description, can_reset);
|
|
g_hash_table_insert (profiler->counters, GINT_TO_POINTER (id), counter);
|
|
|
|
return counter->id;
|
|
}
|
|
|
|
static NamedTimer *
|
|
named_timer_new (GQuark id,
|
|
const char *description,
|
|
gboolean invert,
|
|
gboolean can_reset)
|
|
{
|
|
NamedTimer *res = g_slice_new0 (NamedTimer);
|
|
|
|
res->id = id;
|
|
res->description = g_strdup (description);
|
|
res->invert = invert;
|
|
res->can_reset = can_reset;
|
|
|
|
return res;
|
|
}
|
|
|
|
static NamedTimer *
|
|
gsk_profiler_get_timer (GskProfiler *profiler,
|
|
GQuark id)
|
|
{
|
|
return g_hash_table_lookup (profiler->timers, GINT_TO_POINTER (id));
|
|
}
|
|
|
|
GQuark
|
|
gsk_profiler_add_timer (GskProfiler *profiler,
|
|
const char *timer_name,
|
|
const char *description,
|
|
gboolean invert,
|
|
gboolean can_reset)
|
|
{
|
|
NamedTimer *timer;
|
|
GQuark id;
|
|
|
|
g_return_val_if_fail (GSK_IS_PROFILER (profiler), 0);
|
|
|
|
id = g_quark_from_string (timer_name);
|
|
timer = gsk_profiler_get_timer (profiler, id);
|
|
if (timer != NULL)
|
|
{
|
|
g_critical ("Cannot add a timer '%s' as one already exists.", timer_name);
|
|
return timer->id;
|
|
}
|
|
|
|
timer = named_timer_new (id, description, invert, can_reset);
|
|
g_hash_table_insert (profiler->timers, GINT_TO_POINTER (id), timer);
|
|
|
|
return timer->id;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_counter_inc (GskProfiler *profiler,
|
|
GQuark counter_id)
|
|
{
|
|
gsk_profiler_counter_add (profiler, counter_id, 1);
|
|
}
|
|
|
|
void
|
|
gsk_profiler_counter_set (GskProfiler *profiler,
|
|
GQuark counter_id,
|
|
gint64 value)
|
|
{
|
|
NamedCounter *counter;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
counter = gsk_profiler_get_counter (profiler, counter_id);
|
|
if (counter == NULL)
|
|
{
|
|
g_critical ("No counter '%s' (id:%d) found; did you forget to call gsk_profiler_add_counter()?",
|
|
g_quark_to_string (counter_id), counter_id);
|
|
return;
|
|
}
|
|
|
|
counter->value = value;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_counter_add (GskProfiler *profiler,
|
|
GQuark counter_id,
|
|
gint64 increment)
|
|
{
|
|
NamedCounter *counter;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
counter = gsk_profiler_get_counter (profiler, counter_id);
|
|
if (counter == NULL)
|
|
{
|
|
g_critical ("No counter '%s' (id:%d) found; did you forget to call gsk_profiler_add_counter()?",
|
|
g_quark_to_string (counter_id), counter_id);
|
|
return;
|
|
}
|
|
|
|
counter->value += increment;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_timer_begin (GskProfiler *profiler,
|
|
GQuark timer_id)
|
|
{
|
|
NamedTimer *timer;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
timer = gsk_profiler_get_timer (profiler, timer_id);
|
|
if (timer == NULL)
|
|
return;
|
|
|
|
if (timer->in_flight)
|
|
return;
|
|
|
|
timer->in_flight = TRUE;
|
|
timer->start_time = g_get_monotonic_time ();
|
|
}
|
|
|
|
gint64
|
|
gsk_profiler_timer_end (GskProfiler *profiler,
|
|
GQuark timer_id)
|
|
{
|
|
NamedTimer *timer;
|
|
gint64 diff;
|
|
|
|
g_return_val_if_fail (GSK_IS_PROFILER (profiler), 0);
|
|
|
|
timer = gsk_profiler_get_timer (profiler, timer_id);
|
|
if (timer == NULL)
|
|
{
|
|
g_critical ("No timer '%s' (id:%d) found; did you forget to call gsk_profiler_add_timer()?",
|
|
g_quark_to_string (timer_id), timer_id);
|
|
return 0;
|
|
}
|
|
|
|
if (!timer->in_flight)
|
|
{
|
|
g_critical ("Timer '%s' (id:%d) is not running; did you forget to call gsk_profiler_timer_begin()?",
|
|
g_quark_to_string (timer->id), timer->id);
|
|
return 0;
|
|
}
|
|
|
|
diff = g_get_monotonic_time () - timer->start_time;
|
|
|
|
timer->in_flight = FALSE;
|
|
timer->value += diff;
|
|
|
|
return diff;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_timer_set (GskProfiler *profiler,
|
|
GQuark timer_id,
|
|
gint64 value)
|
|
{
|
|
NamedTimer *timer;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
timer = gsk_profiler_get_timer (profiler, timer_id);
|
|
if (timer == NULL)
|
|
{
|
|
g_critical ("No timer '%s' (id:%d) found; did you forget to call gsk_profiler_add_timer()?",
|
|
g_quark_to_string (timer_id), timer_id);
|
|
return;
|
|
}
|
|
|
|
if (timer->in_flight)
|
|
{
|
|
g_critical ("Timer '%s' (id:%d) is running; are you sure you don't want to call "
|
|
"gsk_profiler_timer_end() instead of gsk_profiler_timer_set()?",
|
|
g_quark_to_string (timer_id), timer_id);
|
|
}
|
|
|
|
timer->value = value;
|
|
}
|
|
|
|
gint64
|
|
gsk_profiler_counter_get (GskProfiler *profiler,
|
|
GQuark counter_id)
|
|
{
|
|
NamedCounter *counter;
|
|
|
|
g_return_val_if_fail (GSK_IS_PROFILER (profiler), 0);
|
|
|
|
counter = gsk_profiler_get_counter (profiler, counter_id);
|
|
if (counter == NULL)
|
|
{
|
|
g_critical ("No counter '%s' (id:%d) found; did you forget to call gsk_profiler_add_counter()?",
|
|
g_quark_to_string (counter_id), counter_id);
|
|
return 0;
|
|
}
|
|
|
|
return counter->value;
|
|
}
|
|
|
|
gint64
|
|
gsk_profiler_timer_get (GskProfiler *profiler,
|
|
GQuark timer_id)
|
|
{
|
|
NamedTimer *timer;
|
|
|
|
g_return_val_if_fail (GSK_IS_PROFILER (profiler), 0);
|
|
|
|
timer = gsk_profiler_get_timer (profiler, timer_id);
|
|
if (timer == NULL)
|
|
{
|
|
g_critical ("No timer '%s' (id:%d) found; did you forget to call gsk_profiler_add_timer()?",
|
|
g_quark_to_string (timer_id), timer_id);
|
|
return 0;
|
|
}
|
|
|
|
if (timer->invert)
|
|
return (gint64) (1000000.0 / (double) timer->value);
|
|
|
|
return timer->value;
|
|
}
|
|
|
|
gint64
|
|
gsk_profiler_timer_get_start (GskProfiler *profiler,
|
|
GQuark timer_id)
|
|
{
|
|
NamedTimer *timer;
|
|
|
|
timer = gsk_profiler_get_timer (profiler, timer_id);
|
|
if (timer == NULL)
|
|
return 0;
|
|
|
|
return timer->start_time;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_reset (GskProfiler *profiler)
|
|
{
|
|
GHashTableIter iter;
|
|
gpointer value_p = NULL;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
g_hash_table_iter_init (&iter, profiler->counters);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedCounter *counter = value_p;
|
|
|
|
if (counter->can_reset)
|
|
counter->value = 0;
|
|
}
|
|
|
|
g_hash_table_iter_init (&iter, profiler->timers);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedTimer *timer = value_p;
|
|
|
|
if (timer->can_reset)
|
|
{
|
|
timer->value = 0;
|
|
timer->min_value = 0;
|
|
timer->max_value = 0;
|
|
timer->avg_value = 0;
|
|
timer->n_samples = 0;
|
|
}
|
|
}
|
|
|
|
profiler->last_sample = 0;
|
|
}
|
|
|
|
void
|
|
gsk_profiler_push_samples (GskProfiler *profiler)
|
|
{
|
|
GHashTableIter iter;
|
|
gpointer value_p = NULL;
|
|
guint last_sample;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
|
|
g_hash_table_iter_init (&iter, profiler->timers);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedTimer *timer = value_p;
|
|
Sample *s;
|
|
|
|
last_sample = profiler->last_sample;
|
|
profiler->last_sample += 1;
|
|
if (profiler->last_sample == MAX_SAMPLES)
|
|
profiler->last_sample = 0;
|
|
|
|
s = &(profiler->timer_samples[last_sample]);
|
|
s->id = timer->id;
|
|
|
|
if (timer->invert)
|
|
s->value = (gint64) (1000000.0 / (double) timer->value);
|
|
else
|
|
s->value = timer->value;
|
|
}
|
|
}
|
|
|
|
void
|
|
gsk_profiler_append_counters (GskProfiler *profiler,
|
|
GString *buffer)
|
|
{
|
|
GHashTableIter iter;
|
|
gpointer value_p = NULL;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
g_return_if_fail (buffer != NULL);
|
|
|
|
g_hash_table_iter_init (&iter, profiler->counters);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedCounter *counter = value_p;
|
|
|
|
g_string_append_printf (buffer, "%s: %" G_GINT64_FORMAT "\n",
|
|
counter->description,
|
|
counter->value);
|
|
}
|
|
}
|
|
|
|
void
|
|
gsk_profiler_append_timers (GskProfiler *profiler,
|
|
GString *buffer)
|
|
{
|
|
GHashTableIter iter;
|
|
gpointer value_p = NULL;
|
|
int i;
|
|
|
|
g_return_if_fail (GSK_IS_PROFILER (profiler));
|
|
g_return_if_fail (buffer != NULL);
|
|
|
|
g_hash_table_iter_init (&iter, profiler->timers);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedTimer *timer = value_p;
|
|
|
|
timer->min_value = G_MAXINT64;
|
|
timer->max_value = G_MININT64;
|
|
timer->avg_value = 0;
|
|
timer->n_samples = 0;
|
|
}
|
|
|
|
for (i = 0; i < profiler->last_sample; i++)
|
|
{
|
|
Sample *s = &(profiler->timer_samples[i]);
|
|
NamedTimer *timer;
|
|
|
|
if (s->id == 0)
|
|
continue;
|
|
|
|
timer = gsk_profiler_get_timer (profiler, s->id);
|
|
timer->min_value = MIN (timer->min_value, s->value);
|
|
timer->max_value = MAX (timer->max_value, s->value);
|
|
timer->avg_value += s->value;
|
|
timer->n_samples += 1;
|
|
}
|
|
|
|
g_hash_table_iter_init (&iter, profiler->timers);
|
|
while (g_hash_table_iter_next (&iter, NULL, &value_p))
|
|
{
|
|
NamedTimer *timer = value_p;
|
|
const char *unit = timer->invert ? "" : "usec";
|
|
|
|
g_string_append_printf (buffer, "%s (%s): %.2f",
|
|
timer->description,
|
|
unit,
|
|
(double) timer->value);
|
|
|
|
if (timer->n_samples > 1)
|
|
{
|
|
timer->avg_value = timer->avg_value / timer->n_samples;
|
|
g_string_append_printf (buffer, " Min: %.2f Avg: %.2f Max: %.2f (%" G_GINT64_FORMAT " samples)",
|
|
(double) timer->min_value,
|
|
(double) timer->avg_value,
|
|
(double) timer->max_value,
|
|
timer->n_samples);
|
|
}
|
|
|
|
g_string_append (buffer, "\n");
|
|
}
|
|
}
|