logging: add new TRACE level

beets/autotag/match.py

@@ -26,6 +26,7 @@
 
 from beets import config, logging, metadata_plugins
 from beets.autotag import AlbumInfo, AlbumMatch, TrackInfo, TrackMatch, hooks
+from beets.logging import TRACE
 from beets.util import get_most_common_tags
 
 from .distance import VA_ARTISTS, distance, track_distance
@@ -76,12 +77,12 @@ def assign_items(
     objects. These "extra" objects occur when there is an unequal number
     of objects of the two types.
     """
-    log.debug("Computing track assignment...")
+    log.log(TRACE, "Computing track assignment...")
     # Construct the cost matrix.
     costs = [[float(track_distance(i, t)) for t in tracks] for i in items]
     # Assign items to tracks
     _, _, assigned_item_idxs = lap.lapjv(np.array(costs), extend_cost=True)
-    log.debug("...done.")
+    log.log(TRACE, "...done.")
 
     # Each item in `assigned_item_idxs` list corresponds to a track in the
     # `tracks` list. Each value is either an index into the assigned item in

beets/logging.py

@@ -33,12 +33,14 @@
     Logger,
     NullHandler,
     StreamHandler,
+    addLevelName,
 )
 
 __all__ = [
     "DEBUG",
     "INFO",
     "NOTSET",
+    "TRACE",
     "WARNING",
     "FileHandler",
     "Filter",
@@ -49,6 +51,10 @@
     "getLogger",
 ]
 
+TRACE = 5
+
+addLevelName(TRACE, "TRACE")
+
 
 def logsafe(val):
     """Coerce `bytes` to `str` to avoid crashes solely due to logging.

beets/plugins.py

@@ -30,6 +30,7 @@
 
 import beets
 from beets import logging
+from beets.logging import TRACE
 
 if TYPE_CHECKING:
     from beets.event_types import EventType
@@ -499,7 +500,7 @@ def send(event: str, **arguments: Any) -> list[Any]:
 
     Return a list of non-None values returned from the handlers.
     """
-    log.debug("Sending event: {0}", event)
+    log.log(TRACE, "Sending event: {0}", event)
     results: list[Any] = []
     for handler in event_handlers()[event]:
         result = handler(**arguments)

test/test_library.py

@@ -139,7 +139,7 @@ def test_library_add_one_database_change_event(self):
         self.item.album = "a"
         self.item.title = "b"
 
-        blog.getLogger("beets").set_global_level(blog.DEBUG)
+        blog.getLogger("beets").set_global_level(blog.TRACE)
         with capture_log() as logs:
             self.lib.add(self.item)
 

test/test_logging.py

@@ -265,7 +265,7 @@ def test_root_logger_levels(self):
             assert "import" in line
             assert "album" in line
 
-        blog.getLogger("beets").set_global_level(blog.DEBUG)
+        blog.getLogger("beets").set_global_level(blog.TRACE)
         with helper.capture_log() as logs:
             self.run_asis_importer()
         assert "Sending event: database_change" in logs

test/test_plugins.py

@@ -20,6 +20,7 @@
 import pytest
 from mediafile import MediaFile
 
+import beets.logging as blog
 from beets import config, plugins, ui
 from beets.dbcore import types
 from beets.importer import (
@@ -158,6 +159,7 @@ def setUp(self):
     def test_import_task_created(self):
         self.importer = self.setup_importer(pretend=True)
 
+        blog.getLogger("beets").set_global_level(blog.TRACE)
         with helper.capture_log() as logs:
             self.importer.run()
 
@@ -200,6 +202,7 @@ def import_task_created_event(self, session, task):
 
         self.importer = self.setup_importer(pretend=True)
 
+        blog.getLogger("beets").set_global_level(blog.TRACE)
         with helper.capture_log() as logs:
             self.importer.run()