add performance logging

Author: Computerdores

tagstudio/src/core/library/alchemy/library.py

@@ -540,13 +540,16 @@ def search_library(
         with Session(self.engine, expire_on_commit=False) as session:
             statement = select(Entry)
 
+            start = time.time()
             if search.ast:
                 statement = (
                     statement.outerjoin(Entry.tag_box_fields)
                     .outerjoin(TagBoxField.tags)
                     .outerjoin(TagAlias)
                     .where(SQLBoolExpressionBuilder().visit(search.ast))
                 )
+            end = time.time()
+            logger.info(f"Building SQL Done in {format_timespan(end-start)}")
 
             extensions = self.prefs(LibraryPrefs.EXTENSION_LIST)
             is_exclude_list = self.prefs(LibraryPrefs.IS_EXCLUDE_LIST)
@@ -568,7 +571,10 @@ def search_library(
             query_count = select(func.count(distinct(aliased_statement.c.id))).select_from(
                 aliased_statement
             )
+            start = time.time()
             count_all: int = session.execute(query_count).scalar()
+            end = time.time()
+            logger.info(f"SQL Count Query done in {format_timespan(end-start)}")
 
             statement = statement.limit(search.limit).offset(search.offset)
 
@@ -578,10 +584,13 @@ def search_library(
                 query_full=str(statement.compile(compile_kwargs={"literal_binds": True})),
             )
 
+            start = time.time()
             res = SearchResult(
                 total_count=count_all,
                 items=list(session.scalars(statement).unique()),
             )
+            end = time.time()
+            logger.info(f"SQL Results Query done in {format_timespan(end-start)}")
 
             session.expunge_all()