.gitignore

@@ -11,4 +11,3 @@ StudioPlaylist.png
 *.howto
 .direnv
 tmp/
-.coverage

app/music.py

@@ -44,7 +44,7 @@ class FadeTrack(QRunnable):
             sleep(1 / Config.FADEOUT_STEPS_PER_SECOND)
 
         self.player.stop()
-        log.debug(f"Releasing player {self.player=}")
+        log.error(f"Releasing player {self.player=}")
         self.player.release()
 
 

app/musicmuster.py

@@ -1146,31 +1146,34 @@ class Window(QMainWindow, Ui_MainWindow):
                     break
             sleep(0.1)
 
+        # TODO: remove sleep() calls - used to try to isolate bug #223
         # Show closing volume graph
+        sleep(1)
         if track_sequence.now.fade_graph:
             track_sequence.now.fade_graph.plot()
         else:
             log.error("No fade_graph")
 
         # Note that track is playing
-        log.debug("set track_sequence")
+        sleep(1)
+        log.error("set track_sequence")
         track_sequence.now.start()
         self.playing = True
 
         # Disable play next controls
+        sleep(1)
         log.error("catch return key")
         self.catch_return_key = True
         self.show_status_message("Play controls: Disabled", 0)
 
         # Notify model
-        log.error("Pause before calling active_proxy_model().current_track_started()")
+        sleep(1)
-        sleep(2)
+        log.error("active_proxy_model().current_track_started()")
         self.active_proxy_model().current_track_started()
-        sleep(2)
-        log.error("Pause finished after calling active_proxy_model().current_track_started()")
 
         # Update headers
-        log.debug("update headers")
+        sleep(1)
+        log.error("update headers")
         self.update_headers()
 
     def preview(self) -> None:

app/playlistmodel.py

@@ -5,6 +5,7 @@ import re
 from datetime import datetime, timedelta
 from enum import auto, Enum
 from operator import attrgetter
+from time import sleep
 from random import shuffle
 from typing import List, Optional
 
@@ -271,18 +272,21 @@ class PlaylistModel(QAbstractTableModel):
             )
             return
 
+        # TODO: remove sleep/log calls, used to debug #223
         # Check for OBS scene change
-        # TODO: log.error used to debug #223 - change to log.debug later
+        sleep(1)
-        log.debug("Call OBS scene change")
+        log.error("Call OBS scene change")
         self.obs_scene_change(row_number)
 
         with Session() as session:
             # Update Playdates in database
-            log.debug("update playdates")
+            sleep(1)
+            log.error("update playdates")
             Playdates(session, track_sequence.now.track_id)
 
             # Mark track as played in playlist
-            log.debug("Mark track as played")
+            sleep(1)
+            log.error("Mark track as played")
             plr = session.get(PlaylistRows, track_sequence.now.plr_id)
             if plr:
                 plr.played = True
@@ -291,7 +295,8 @@ class PlaylistModel(QAbstractTableModel):
                 log.error(f"Can't retrieve plr, {track_sequence.now.plr_id=}")
 
             # Update track times
-            log.debug("Update track times")
+            sleep(1)
+            log.error("Update track times")
             if prd:
                 prd.start_time = track_sequence.now.start_time
                 prd.end_time = track_sequence.now.end_time
@@ -308,7 +313,8 @@ class PlaylistModel(QAbstractTableModel):
 
             # Find next track
             # Get all unplayed track rows
-            log.debug("Find next track")
+            sleep(1)
+            log.error("Find next track")
             next_row = None
             unplayed_rows = self.get_unplayed_rows()
             if unplayed_rows: