Mercurial > hg
diff mercurial/extensions.py @ 39511:1ab185c78cc3
extension: add a summary of total loading time per extension
Differential Revision: https://phab.mercurial-scm.org/D4513
author | Boris Feld <boris.feld@octobus.net> |
---|---|
date | Fri, 07 Sep 2018 16:51:51 -0400 |
parents | 3a86f7eb8b78 |
children | 09a37a5d8f5d |
line wrap: on
line diff
--- a/mercurial/extensions.py Thu Sep 06 16:49:37 2018 -0400 +++ b/mercurial/extensions.py Fri Sep 07 16:51:51 2018 -0400 @@ -166,7 +166,7 @@ _rejectunicode(t, o._table) _validatecmdtable(ui, getattr(mod, 'cmdtable', {})) -def load(ui, name, path, log=lambda *a: None): +def load(ui, name, path, log=lambda *a: None, loadingtime=None): if name.startswith('hgext.') or name.startswith('hgext/'): shortname = name[6:] else: @@ -180,6 +180,8 @@ with util.timedcm('load extension %r', shortname) as stats: mod = _importext(name, path, bind(_reportimporterror, ui)) log(' > %r extension loaded in %s\n', shortname, stats) + if loadingtime is not None: + loadingtime[shortname] += stats.elapsed # Before we do anything with the extension, check against minimum stated # compatibility. This gives extension authors a mechanism to have their @@ -237,6 +239,7 @@ msg % values, label='debug.extensions') else: log = lambda *a, **kw: None + loadingtime = collections.defaultdict(int) result = ui.configitems("extensions") if whitelist is not None: result = [(k, v) for (k, v) in result if k in whitelist] @@ -252,7 +255,7 @@ _disabledextensions[name] = path[1:] continue try: - load(ui, name, path, log) + load(ui, name, path, log, loadingtime) except Exception as inst: msg = stringutil.forcebytestr(inst) if path: @@ -292,6 +295,7 @@ log(' - the %r extension uisetup failed\n', name) broken.add(name) log(' > uisetup for %r took %s\n', name, stats) + loadingtime[name] += stats.elapsed log('> all uisetup took %s\n', alluisetupstats) log('- executing extsetup hooks\n') @@ -305,6 +309,7 @@ log(' - the %r extension extsetup failed\n', name) broken.add(name) log(' > extsetup for %r took %s\n', name, stats) + loadingtime[name] += stats.elapsed log('> all extsetup took %s\n', allextetupstats) for name in broken: @@ -360,6 +365,12 @@ with util.timedcm('load registration objects') as stats: _loadextra(ui, newindex, extraloaders) log('> extension registration object loading took %s\n', stats) + + # Report per extension loading time (except reposetup) + for name in sorted(loadingtime): + extension_msg = '> extension %s take a total of %s to load\n' + log(extension_msg, name, util.timecount(loadingtime[name])) + log('extension loading complete\n') def _loadextra(ui, newindex, extraloaders):