extensions: trace the total time of running all reposetup callbacks
authorBoris Feld <boris.feld@octobus.net>
Thu, 06 Sep 2018 16:49:37 -0400
changeset 39527 340170192874
parent 39526 3a86f7eb8b78
child 39528 1ab185c78cc3
extensions: trace the total time of running all reposetup callbacks Differential Revision: https://phab.mercurial-scm.org/D4512
mercurial/hg.py
tests/test-extension-timing.t
--- a/mercurial/hg.py	Thu Sep 06 16:21:42 2018 -0400
+++ b/mercurial/hg.py	Thu Sep 06 16:49:37 2018 -0400
@@ -170,13 +170,15 @@
     for f in presetupfuncs or []:
         f(ui, obj)
     log('- executing reposetup hooks\n')
-    for name, module in extensions.extensions(ui):
-        log('  - running reposetup for %s\n' % (name,))
-        hook = getattr(module, 'reposetup', None)
-        if hook:
-            with util.timedcm('reposetup %r', name) as stats:
-                hook(ui, obj)
-            log('  > reposetup for %r took %s\n', name, stats)
+    with util.timedcm('all reposetup') as allreposetupstats:
+        for name, module in extensions.extensions(ui):
+            log('  - running reposetup for %s\n' % (name,))
+            hook = getattr(module, 'reposetup', None)
+            if hook:
+                with util.timedcm('reposetup %r', name) as stats:
+                    hook(ui, obj)
+                log('  > reposetup for %r took %s\n', name, stats)
+    log('> all reposetup took %s\n', allreposetupstats)
     if not obj.local():
         for f in wirepeersetupfuncs:
             f(ui, obj)
--- a/tests/test-extension-timing.t	Thu Sep 06 16:21:42 2018 -0400
+++ b/tests/test-extension-timing.t	Thu Sep 06 16:49:37 2018 -0400
@@ -87,6 +87,7 @@
   reposetup called for a
   ui == repo.ui
   debug.extensions:   > reposetup for 'foobar' took * (glob)
+  debug.extensions: > all reposetup took * (glob)
   Foo
 
   $ cd ..