Add more logging. Useful to understand `git try` slowness.

gclient_utils.py

 # Copyright (c) 2010 The Chromium Authors. All rights reserved.
 # Use of this source code is governed by a BSD-style license that can be
 # found in the LICENSE file.
 
 """Generic utils."""
 
 import errno
 import logging
 import os
 import Queue
@@ skipping 69 matching lines @@
           'learn how to fix this error; you need to rebase your cygwin dlls')
     raise
 
 
 def CheckCall(command, print_error=True, **kwargs):
   """Similar subprocess.check_call() but redirects stdout and
   returns (stdout, stderr).
 
   Works on python 2.4
   """
+  logging.info("CheckCall(%s)" % command)
   try:
     stderr = None
     if not print_error:
       stderr = subprocess.PIPE
     process = Popen(command, stdout=subprocess.PIPE, stderr=stderr, **kwargs)
     std_out, std_err = process.communicate()
   except OSError, e:
     raise CheckCallError(command, kwargs.get('cwd', None), e.errno, None)
   if process.returncode:
     raise CheckCallError(command, kwargs.get('cwd', None), process.returncode,
         std_out, std_err)
+  logging.info("CheckCall done")
   return std_out, std_err
 
 
 def SplitUrlRevision(url):
   """Splits url and returns a two-tuple: url, rev"""
   if url.startswith('ssh:'):
     # Make sure ssh://user-name@example.com/~/test.git@stable works
     regex = r'(ssh://(?:[-\w]+@)?[-\w:\.]+/[-~\w\./]+)(?:@(.+))?'
     components = re.search(regex, url).groups()
   else:
@@ skipping 597 matching lines @@
         logging.info('Caught exception in thread %s' % self.item.name)
         logging.info(str(sys.exc_info()))
         work_queue.exceptions.put(sys.exc_info())
       logging.info('Task %s done' % self.item.name)
 
       work_queue.ready_cond.acquire()
       try:
         work_queue.ready_cond.notifyAll()
       finally:
         work_queue.ready_cond.release()