adding execution time logging

src/qseek/distance_weights.py

@@ -9,6 +9,7 @@
 from pydantic import BaseModel, ByteSize, Field, PositiveFloat, PrivateAttr
 
 from qseek.octree import get_node_coordinates
+from qseek.utils import alog_call
 
 if TYPE_CHECKING:
     from qseek.models.station import Station, Stations
@@ -114,6 +115,7 @@ def lut_fill_level(self) -> float:
         """Return the fill level of the LUT as a float between 0.0 and 1.0."""
         return len(self._node_lut) / self._node_lut.get_size()
 
+    @alog_call
     async def get_weights(
         self, nodes: Sequence[Node], stations: Stations
     ) -> np.ndarray:

src/qseek/tracers/cake.py

@@ -38,6 +38,7 @@
 from qseek.utils import (
     CACHE_DIR,
     PhaseDescription,
+    alog_call,
     datetime_now,
     human_readable_bytes,
 )
@@ -658,6 +659,7 @@ def get_travel_time_location(
             raise ValueError(f"Phase {phase} is not defined.") from exc
         return tree.get_travel_time(source, receiver)
 
+    @alog_call
     async def get_travel_times(
         self,
         phase: str,

src/qseek/utils.py

@@ -385,8 +385,11 @@ def log_call(func: Callable[P, T]) -> Callable[P, T]:
     def wrapper(*args: P.args, **kwargs: P.kwargs) -> T:
         start = time.time()
         ret = func(*args, **kwargs)
-        duration = timedelta(seconds=time.time() - start)
-        logger.debug("executed %s in %s", func.__qualname__, duration)
+        logger.debug(
+            "executed %s in %s",
+            func.__qualname__,
+            timedelta(seconds=time.time() - start),
+        )
         return ret
 
     return wrapper
@@ -397,8 +400,11 @@ def alog_call(func: Callable[P, Awaitable[T]]) -> Callable[P, Awaitable[T]]:
     async def wrapper(*args: P.args, **kwargs: P.kwargs) -> T:
         start = time.time()
         ret = await func(*args, **kwargs)
-        duration = timedelta(seconds=time.time() - start)
-        logger.debug("executed %s in %s", func.__qualname__, duration)
+        logger.debug(
+            "executed %s in %s",
+            func.__qualname__,
+            timedelta(seconds=time.time() - start),
+        )
         return ret
 
     return wrapper