summary refs log tree commit diff
diff options
context:
space:
mode:
authorErik Johnston <erik@matrix.org>2021-06-16 13:29:54 +0100
committerGitHub <noreply@github.com>2021-06-16 13:29:54 +0100
commit36c426e294a53d2192cc9f29ec5c93e84e222228 (patch)
tree1fb8a65111212a563b372d4a6d741c289f25dcae
parentFix requestOpenIdToken response: integer expires_in (#10175) (diff)
downloadsynapse-36c426e294a53d2192cc9f29ec5c93e84e222228.tar.xz
Add debug logging when we enter/exit Measure block (#10183)
It can be helpful to know when trying to track down slow requests.
Diffstat (limited to '')
-rw-r--r--changelog.d/10183.misc1
-rw-r--r--synapse/util/metrics.py5
2 files changed, 6 insertions, 0 deletions
diff --git a/changelog.d/10183.misc b/changelog.d/10183.misc
new file mode 100644
index 0000000000..c0e01ad3db
--- /dev/null
+++ b/changelog.d/10183.misc
@@ -0,0 +1 @@
+Add debug logging for when we enter and exit `Measure` blocks.
diff --git a/synapse/util/metrics.py b/synapse/util/metrics.py
index 6d14351bd2..45353d41c5 100644
--- a/synapse/util/metrics.py
+++ b/synapse/util/metrics.py
@@ -133,12 +133,17 @@ class Measure:
         self.start = self.clock.time()
         self._logging_context.__enter__()
         in_flight.register((self.name,), self._update_in_flight)
+
+        logger.debug("Entering block %s", self.name)
+
         return self
 
     def __exit__(self, exc_type, exc_val, exc_tb):
         if self.start is None:
             raise RuntimeError("Measure() block exited without being entered")
 
+        logger.debug("Exiting block %s", self.name)
+
         duration = self.clock.time() - self.start
         usage = self.get_resource_usage()