From 9dba2a52db2adb42e7c1a292625bd0114ce81969 Mon Sep 17 00:00:00 2001
From: wm4 <wm4@nowhere>
Date: Thu, 17 Apr 2014 21:47:00 +0200
Subject: player: add a --dump-stats option

This collects statistics and other things. The option dumps raw data
into a file. A script to visualize this data is included too.

Litter some of the player code with calls that generate these
statistics.

In general, this will be helpful to debug timing dependent issues, such
as A/V sync problems. Normally, one could argue that this is the task of
a real profiler, but then we'd have a hard time to include extra
information like audio/video PTS differences. We could also just
hardcode all statistics collection and processing in the player code,
but then we'd end up with something like mplayer's status line, which
was cluttered and required a centralized approach (i.e. getting the data
to the status line; so it was all in mplayer.c). Some players can
visualize such statistics on OSD, but that sounds even more complicated.
So the approach added with this commit sounds sensible.

The stats-conv.py script is rather primitive at the moment and its
output is semi-ugly. It uses matplotlib, so it could probably be
extended to do a lot, so it's not a dead-end.
---
 TOOLS/stats-conv.py | 88 +++++++++++++++++++++++++++++++++++++++++++++++++++++
 1 file changed, 88 insertions(+)
 create mode 100755 TOOLS/stats-conv.py

(limited to 'TOOLS/stats-conv.py')

diff --git a/TOOLS/stats-conv.py b/TOOLS/stats-conv.py
new file mode 100755
index 0000000000..941cb1539e
--- /dev/null
+++ b/TOOLS/stats-conv.py
@@ -0,0 +1,88 @@
+#!/usr/bin/env python3
+import matplotlib.pyplot as plot
+import sys
+
+filename = sys.argv[1]
+
+"""
+This script is meant to display stats written by mpv --dump-stats=filename.
+In general, each line in that file is an event of the form:
+
+    <timestamp in microseconds> <text> '#' <comment>
+
+e.g.:
+
+    10474959 start flip #cplayer
+
+<text> is what MP_STATS(log, "...") writes. The rest is added by msg.c.
+
+Currently, the following event types are supported:
+
+    'start' <name>          start of the named event
+    'end' <name>            end of the named event
+    'value' <float> <name>  a normal value (as opposed to event)
+    <event>                 singular event
+
+"""
+
+class G:
+    events = {}
+    sevents = []  # events, deterministically sorted
+    start = None
+
+class Event:
+    pass
+
+def get_event(event):
+    if event not in G.events:
+        e = Event()
+        G.events[event] = e
+        e.name = event
+        e.vals = []
+        e.type = "unknown"
+        G.sevents = list(G.events.values())
+        G.sevents.sort(key=lambda x: x.name)
+    return G.events[event]
+
+for line in [line.split("#")[0].strip() for line in open(filename, "r")]:
+    ts, event = line.split(" ", 1)
+    ts = int(ts) / 1000 # milliseconds
+    if G.start is None:
+        G.start = ts
+    ts = ts - G.start
+    if event.startswith("start "):
+        e = get_event(event[6:])
+        e.type = "event"
+        e.vals.append((ts, 0))
+        e.vals.append((ts, 1))
+    elif event.startswith("end "):
+        e = get_event(event[4:])
+        e.type = "event"
+        e.vals.append((ts, 1))
+        e.vals.append((ts, 0))
+    elif event.startswith("value "):
+        _, val, name = event.split(" ", 2)
+        val = float(val)
+        e = get_event(name)
+        e.type = "value"
+        e.vals.append((ts, val))
+    else:
+        e = get_event(event)
+        e.type = "event-signal"
+        e.vals.append((ts, 1))
+
+plot.hold(True)
+mainpl = plot.subplot(2, 1, 1)
+legend = []
+for e in G.sevents:
+    if e.type == "value":
+        plot.subplot(2, 1, 2, sharex=mainpl)
+    else:
+        plot.subplot(2, 1, 1)
+    pl, = plot.plot([x for x,y in e.vals], [y for x,y in e.vals], label=e.name)
+    if e.type == "event-signal":
+        plot.setp(pl, marker = "o", linestyle = "None")
+    legend.append(pl)
+plot.subplot(2, 1, 1)
+plot.legend(legend, [pl.get_label() for pl in legend])
+plot.show()
-- 
cgit v1.2.3