# HG changeset patch
# User Marcin Kuzminski <marcin@rhodecode.com>
# Date 2017-02-21 21:41:56
# Node ID 7e5ee31604d437c48e8c48d7c87dc31a000fa965
# Parent  01fbc7af12d6520534cdeb451eb32d960641ea10

hooks: added extra logging.

diff --git a/rhodecode/lib/hooks_base.py b/rhodecode/lib/hooks_base.py
--- a/rhodecode/lib/hooks_base.py
+++ b/rhodecode/lib/hooks_base.py
@@ -25,6 +25,7 @@ Set of hooks run by RhodeCode Enterprise
 
 import os
 import collections
+import logging
 
 import rhodecode
 from rhodecode import events
@@ -34,6 +35,8 @@ from rhodecode.lib.utils2 import safe_st
 from rhodecode.lib.exceptions import HTTPLockedRC, UserCreationError
 from rhodecode.model.db import Repository, User
 
+log = logging.getLogger(__name__)
+
 
 HookResponse = collections.namedtuple('HookResponse', ('status', 'output'))
 
@@ -91,7 +94,6 @@ def pre_push(extras):
     It bans pushing when the repository is locked.
     """
     usr = User.get_by_username(extras.username)
-
     output = ''
     if extras.locked_by[0] and usr.user_id != int(extras.locked_by[0]):
         locked_by = User.get(extras.locked_by[0]).username
@@ -251,10 +253,14 @@ class ExtensionCallback(object):
         self._kwargs_keys = set(kwargs_keys)
 
     def __call__(self, *args, **kwargs):
+        log.debug('Calling extension callback for %s', self._hook_name)
+
         kwargs_to_pass = dict((key, kwargs[key]) for key in self._kwargs_keys)
         callback = self._get_callback()
         if callback:
             return callback(**kwargs_to_pass)
+        else:
+            log.debug('extensions callback not found skipping...')
 
     def is_active(self):
         return hasattr(rhodecode.EXTENSIONS, self._hook_name)