docs: update CHANGELOG and enhance Django/pytest startup logging

- Updated `CHANGELOG.md` to reflect improvements in logging visibility during Django and pytest startup processes, including the installation of `CiPytestStartupTracer` for better tracking of `AppConfig.ready()` calls.
- Enhanced logging in `api/models.py` to indicate when Django will call `AppConfig.ready()` for each installed app.
- Removed unnecessary import from `api/apps.py` to streamline the code.

Co-authored-by: Cursor <cursoragent@cursor.com>

Author: Andreas-Garcia

CHANGELOG.md

@@ -84,7 +84,7 @@ All contributors (including maintainers) should update `CHANGELOG.md` when creat
 
 ### Improved
 
-- **Django / pytest startup visibility**: [`api/utils/utils.py`](api/utils/utils.py) **`print_django`** uses **`flush=True`**. [`api/settings.py`](api/settings.py) logs that **`django.setup()`** follows settings. [`api/models.py`](api/models.py) logs before/after the **`api`** model import chain (often the long gap before **`api.urls`**). [`api/urls.py`](api/urls.py) logs start/end of URLconf import; [`api/apps.py`](api/apps.py) **`ApiConfig.ready()`** logs when the **`api`** app **`ready()`** runs. [`api/test/tests/conftest.py`](api/test/tests/conftest.py) keeps **`[pytest]`** session/collection progress.
+- **Django / pytest startup visibility**: [`api/utils/utils.py`](api/utils/utils.py) **`print_django`** uses **`flush=True`**. [`api/settings.py`](api/settings.py) logs that **`django.setup()`** follows settings and installs [`api/CiPytestStartupTracer.py`](api/CiPytestStartupTracer.py) **`CiPytestStartupTracer`** (logs every **`AppConfig.ready()`** start/end in pytest/CI so a stall after **`api.models`** points at the right installed app). [`api/models.py`](api/models.py) logs before/after the **`api`** model import chain and notes that **`ready()`** runs next. [`api/urls.py`](api/urls.py) logs start/end of URLconf import (loads after **`apps.populate()`**). [`api/test/tests/conftest.py`](api/test/tests/conftest.py) keeps **`[pytest]`** session/collection progress.
 
 ### Changed
 

api/CiPytestStartupTracer.py

@@ -0,0 +1,30 @@
+import os
+import sys
+from collections.abc import Callable
+
+from django.apps import AppConfig
+
+
+class CiPytestStartupTracer:
+    """Logs each AppConfig.ready() in CI/pytest so hangs after model import are attributable to a specific app."""
+
+    @staticmethod
+    def is_ci_or_pytest_argv() -> bool:
+        if os.environ.get("ENV") == "ci_test":
+            return True
+        if "pytest" in (sys.argv[0] or ""):
+            return True
+        return any(a == "pytest" for a in sys.argv)
+
+    @staticmethod
+    def install_appconfig_ready_tracer() -> None:
+        if not CiPytestStartupTracer.is_ci_or_pytest_argv():
+            return
+        original_ready: Callable[[AppConfig], None] = AppConfig.ready
+
+        def traced_ready(self: AppConfig) -> None:
+            print(f"[Django] AppConfig.ready() start: {self.label}", flush=True)
+            original_ready(self)
+            print(f"[Django] AppConfig.ready() end: {self.label}", flush=True)
+
+        AppConfig.ready = traced_ready  # type: ignore[method-assign]

api/apps.py

@@ -3,8 +3,6 @@
 
 from django.apps import AppConfig
 
-from . import settings
-
 
 class ApiConfig(AppConfig):
     name = "api"

api/models.py

@@ -13,5 +13,9 @@
 
 if "pytest" in sys.argv[0] or os.environ.get("ENV") == "ci_test":
     print("[Django] api.models: core model imports finished.", flush=True)
+    print(
+        "[Django] api.models: Django will now call AppConfig.ready() on each installed app (api is last).",
+        flush=True,
+    )
 
 __all__ = ["SpotifyArtist", "SpotifyLibTrack", "User"]

api/settings.py

@@ -913,8 +913,11 @@ def set_secret_key():
 
 print_django("Finished loading settings.")
 if "pytest" in sys.argv[0] or os.environ.get("ENV") == "ci_test":
+    from api.CiPytestStartupTracer import CiPytestStartupTracer
+
+    CiPytestStartupTracer.install_appconfig_ready_tracer()
     print(
-        "[Django] Next: django.setup() loads apps/models and ROOT_URLCONF (api.urls); "
-        "this can take minutes in CI with no further [Django] lines until complete.",
+        "[Django] Next: django.setup() runs apps.populate() (models, then one AppConfig.ready() per app); "
+        "ROOT_URLCONF (api.urls) loads only after that. Long gaps: watch which ready() starts but never ends.",
         flush=True,
     )