add more logs

Author: dmyger

test/pytest.ini

@@ -12,3 +12,7 @@ timeout = 900
 
 # Disable legacy paths.
 addopts = -p no:legacypath
+
+log_level = DEBUG
+log_format = %(asctime)s [%(levelname)s] %(name)s/%(funcName)s: %(message)s
+log_date_format = %Y-%m-%d %H:%M:%S.%f

test/utils.py

@@ -648,29 +648,38 @@ def __del__(self):
 
 class Timeout(Timer):
     __timeout: float
+    __is_running: bool
 
     def __init__(self, name, timeout, callback, *args) -> None:
         self.__timeout = timeout
+        self.__is_running = False
         if self.is_timeout:
             super().__init__(timeout, callback, *args)
             self.setName(name)
 
     @property
     def is_timeout(self) -> bool:
-        return self.__timeout > 0
+        return self.__timeout is not None and self.__timeout > 0
 
     def __enter__(self):
+        assert not self.__is_running, "This timer already in use"
         if self.is_timeout:
+            self.__is_running = True
+            logging.getLogger(__name__).debug(
+                f"Start timer='{self.name}' for {self.__timeout} seconds {self.args}"
+            )
             self.start()
         return self
 
     def __exit__(self, unused_exc_type, unused_value, unused_traceback) -> None:
         _ = (unused_exc_type, unused_value, unused_traceback)
         if self.is_timeout:
+            logging.getLogger(__name__).debug(f"Stop(1) timer='{self.name}' {self.args}")
             self.cancel()
 
     def __del__(self) -> None:
         if self.is_timeout:
+            logging.getLogger(__name__).debug(f"Stop(2) timer='{self.name}' {self.args}")
             self.cancel()