клоунада 2

bot/notifier.py

@@ -13,12 +13,29 @@
 from __future__ import annotations
 
 import logging
+import time
 from abc import ABC, abstractmethod
 from dataclasses import dataclass
 
 log = logging.getLogger(__name__)
 
 
+def _timed(label: str):
+    """Context manager: логирует старт и завершение операции с временем выполнения."""
+    import contextlib
+
+    @contextlib.contextmanager
+    def _cm():
+        log.info("⏳ %s ...", label)
+        t0 = time.monotonic()
+        try:
+            yield
+        finally:
+            log.info("✅ %s — %.2fs", label, time.monotonic() - t0)
+
+    return _cm()
+
+
 # ── Агностичное представление клавиатуры ──────────────────────────────────────
 
 @dataclass
@@ -104,48 +121,46 @@ class TelegramChannelNotifier(Notifier):
     # ── Реализация интерфейса ──────────────────────────────────────────────────
 
     def send(self, text: str, keyboard: Keyboard | None = None) -> int:
-        log.info("Telegram send_message → channel %s", self._channel_id)
+        with _timed(f"send_message → channel {self._channel_id}"):
             msg = self._bot.send_message(
                 self._channel_id, text,
                 parse_mode="Markdown",
                 disable_web_page_preview=True,
                 reply_markup=self._to_markup(keyboard),
             )
-        log.info("Telegram send_message OK, message_id=%s", msg.message_id)
+        log.info("send_message OK → message_id=%s", msg.message_id)
         return msg.message_id
 
     def edit_text(self, message_id: int, text: str, keyboard: Keyboard | None = None):
-        log.info("Telegram edit_message_text → message_id=%s", message_id)
+        with _timed(f"edit_message_text → message_id={message_id}"):
             self._bot.edit_message_text(
                 text, self._channel_id, message_id,
                 parse_mode="Markdown",
                 disable_web_page_preview=True,
                 reply_markup=self._to_markup(keyboard),
             )
-        log.info("Telegram edit_message_text OK, message_id=%s", message_id)
 
     def edit_keyboard(self, message_id: int, keyboard: Keyboard | None):
-        log.info("Telegram edit_message_reply_markup → message_id=%s", message_id)
+        with _timed(f"edit_message_reply_markup → message_id={message_id}"):
             self._bot.edit_message_reply_markup(
                 self._channel_id, message_id,
                 reply_markup=self._to_markup(keyboard),
             )
 
     def delete(self, message_id: int):
-        log.info("Telegram delete_message → message_id=%s", message_id)
+        with _timed(f"delete_message → message_id={message_id}"):
             try:
                 self._bot.delete_message(self._channel_id, message_id)
-            log.info("Telegram delete_message OK, message_id=%s", message_id)
             except Exception as e:
-            log.warning("Telegram delete_message failed (message_id=%s): %s", message_id, e)
+                log.warning("delete_message failed (message_id=%s): %s", message_id, e)
 
     def send_animation(self, url: str, disable_notification: bool = True) -> int:
-        log.info("Telegram send_animation → channel %s", self._channel_id)
+        with _timed(f"send_animation → channel {self._channel_id}"):
             msg = self._bot.send_animation(
                 self._channel_id, url,
                 disable_notification=disable_notification,
             )
-        log.info("Telegram send_animation OK, message_id=%s", msg.message_id)
+        log.info("send_animation OK → message_id=%s", msg.message_id)
         return msg.message_id
 
     def is_stale_error(self, exc: Exception) -> bool:

bot/poster.py

@@ -10,6 +10,7 @@
 from __future__ import annotations
 
 import logging
+import time
 
 import config
 import database
@@ -50,6 +51,8 @@ def recreate_post(
     Изменения (• ...) дедуплицируются по тексту.
     Комментарии (💬 ...) и описания (📝 ...) добавляются всегда.
     """
+    t0 = time.monotonic()
+    log.info("recreate_post START task=%s", task_id)
     notifier = get_notifier()
 
     stored     = database.get_stored_events(task_id)
@@ -78,6 +81,7 @@ def recreate_post(
     })
 
     old = database.get_message_data(task_id)
+    log.info("recreate_post task=%s old_msg=%s", task_id, old["message_id"] if old else None)
 
     try:
         message_id = notifier.send(full_text, keyboard)
@@ -91,6 +95,8 @@ def recreate_post(
     if old:
         notifier.delete(old["message_id"])
 
+    log.info("recreate_post DONE task=%s — %.2fs total", task_id, time.monotonic() - t0)
+
 
 def refresh_responsible(task_id: int, task_number: str):
     """Редактирует текст поста — обновляет блок «В работе»."""