Add logs for time tracking

iib/workers/api_utils.py

@@ -9,6 +9,7 @@
 
 from iib.exceptions import IIBError
 from iib.workers.tasks.iib_static_types import UpdateRequestPayload
+import time
 
 log = logging.getLogger(__name__)
 
@@ -133,14 +134,17 @@ def update_request(
     :raises IIBError: if the request to the IIB API fails
     """
     # Prevent a circular import
+    start_time = time.time()
     from iib.workers.config import get_worker_config
 
     config = get_worker_config()
     request_url = f'{config.iib_api_url.rstrip("/")}/builds/{request_id}'
     log.info('Patching the request %d with %r', request_id, payload)
 
     try:
+        patch_start_time = time.time()
         rv = requests_auth_session.patch(request_url, json=payload, timeout=config.iib_api_timeout)
+        log.info(f"Update_request patch duration: {time.time() - patch_start_time}")
     except requests.RequestException:
         msg = f'The connection failed when updating the request {request_id}'
         log.exception(msg)
@@ -158,7 +162,7 @@ def update_request(
         else:
             _exc_msg = f'The worker failed to update the request {request_id}'
         raise IIBError(_exc_msg)
-
+    log.info(f"Update_request duration: {time.time() - start_time}")
     return rv.json()