[PATCH 8 of 8 V3] copies: add time information to the debug information

[Boris Feld]

# HG changeset patch
# User Boris Feld <boris.feld at octobus.net>
# Date 1536335028 14400
#      Fri Sep 07 11:43:48 2018 -0400
# Node ID 5780db5f3a36b6450899b21c8fd033a348eee7db
# Parent  2b5e633c984e0341b2606e66cfafc92f27c10876
# EXP-Topic copy-perf
# Available At https://bitbucket.org/octobus/mercurial-devel/
#              hg pull https://bitbucket.org/octobus/mercurial-devel/ -r 5780db5f3a36
copies: add time information to the debug information

diff --git a/mercurial/copies.py b/mercurial/copies.py
--- a/mercurial/copies.py
+++ b/mercurial/copies.py
@@ -204,11 +204,16 @@ def _committedforwardcopies(a, b, match)
         fctx = b[f]
         fctx._ancestrycontext = ancestrycontext
 
+        if debug:
+            start = util.timer()
         ofctx = _tracefile(fctx, am, limit)
         if ofctx:
             if debug:
                 dbg('debug.copies:          rename of: %s\n' % ofctx._path)
             cm[f] = ofctx.path()
+        if debug:
+            dbg('debug.copies:          time: %s seconds\n'
+                % (util.timer() - start))
     return cm
 
 def _forwardcopies(a, b, match=None):
diff --git a/tests/test-mv-cp-st-diff.t b/tests/test-mv-cp-st-diff.t
--- a/tests/test-mv-cp-st-diff.t
+++ b/tests/test-mv-cp-st-diff.t
@@ -1676,6 +1676,7 @@ Check debug output for copy tracing
   debug.copies:      missing file to search: 1
   debug.copies:        tracing file: renamed
   debug.copies:          rename of: f
+  debug.copies:          time: * seconds (glob)
   A renamed
     f
   R f