Any thoughts on what might be happening?
Here's a dump of logs from DbgView where I switch to MM and immediately select a playlist called "Latenight Candlemusic". (I inserted some spaces because Songs dot ID was being flagged as a link to another domain.) The database work all seems to take less than a tenth of a second, and there are gaps in time where it seems nothing is happening.
Code: Select all
00000001 0.00000000 [16468] Open autolaunch key ok
00000002 0.27819169 [28468] Forms: Received WM_ACTIVATEAPP
00000003 0.27822021 [28468] Forms: Accepted WM_ACTIVATEAPP +
00000004 0.27824569 [28468] Forms: RestoreTopMost: Level: 1
00000005 0.27830309 [28468] Forms: RestoreTopMost: Starting
00000006 0.27854449 [28468] Winamp simulation window received messsage 28
00000007 0.28118989 [28468] MainForm: NC Activate +
00000008 0.99954200 [16468] Open autolaunch key ok
00000009 1.97261834 [28468] Node Latenight Candlemusic starting.
00000010 1.97334361 [28468] MM4 [30568] TImagePlus.Paint: start
00000011 1.97367442 [28468] MM4 [30568] TImagePlus.Paint: finished
00000012 1.98954499 [28468] Node Latenight Candlemusic going to clear SongList.
00000013 1.98970306 [28468] Events: Invoking event: z9
00000014 1.98980176 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000015 1.98991036 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000016 1.98992801 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000017 1.99034142 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000018 1.99041080 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000019 1.99051082 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000020 1.99410224 [16468] Open autolaunch key ok
00000021 3.00113249 [16468] Open autolaunch key ok
00000022 3.99814153 [16468] Open autolaunch key ok
00000023 4.68227577 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000024 4.68229342 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000025 4.68238497 [28468] Events: Successfully finished event: z9
00000026 4.68394470 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000027 4.68403435 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000028 4.68418646 [28468] 30568 DB lock finished, took 2097.
00000029 4.68427896 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000030 4.68438244 [28468] 30568 DB lock finished, took 723.
00000031 4.68439579 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000032 4.68446493 [28468] 30568 DB lock finished, took 71.
00000033 4.68456221 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000034 4.68464518 [28468] 30568 DB lock finished, took 590.
00000035 4.68573618 [28468] MainForm: NC Activate -
00000036 4.68649530 [28468] Forms: Received WM_ACTIVATEAPP
00000037 4.68658257 [28468] Forms: Accepted WM_ACTIVATEAPP -
00000038 4.68680143 [28468] Forms: NormalizeTopMosts: Level: 0
00000039 4.68691540 [28468] Winamp simulation window received messsage 28
00000040 4.68779659 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000041 4.68781996 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000042 4.68792963 [28468] 30568 DB lock finished, took 1072.
00000043 4.68796015 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000044 4.68804121 [28468] 30568 DB lock finished, took 569.
00000045 4.68808651 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000046 4.68811274 [28468] 30568 DB lock finished, took 51.
00000047 4.68821621 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000048 4.68829584 [28468] 30568 DB lock finished, took 563.
00000049 4.68855429 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000050 4.68857622 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000051 4.68867111 [28468] 30568 DB lock finished, took 918.
00000052 4.68870306 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000053 4.68878126 [28468] 30568 DB lock finished, took 547.
00000054 4.68882561 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000055 4.68884945 [28468] 30568 DB lock finished, took 33.
00000056 4.68895102 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000057 4.68903017 [28468] 30568 DB lock finished, took 552.
00000058 4.68951082 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000059 4.68953323 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000060 4.68963861 [28468] 30568 DB lock finished, took 1026.
00000061 4.68967199 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000062 4.68975306 [28468] 30568 DB lock finished, took 575.
00000063 4.68979740 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000064 4.68982220 [28468] 30568 DB lock finished, took 35.
00000065 4.68992424 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000066 4.69000340 [28468] 30568 DB lock finished, took 553.
00000067 4.70030022 [28468] Going to fill tracklist, NodeType: 61
00000068 4.70040941 [28468] Going to start filling thread, FFillingThreadCnt = 10
00000069 4.72704697 [28468] TGenThread.Create: ThreadName: FillingThread , Running Threads: Internal: 0, All: 23
00000070 4.72855616 [28468] Thread 'FillingThread' has started 14464
00000071 4.73036289 [28468] 14464 DB open SQL: SELECT Songs.*
00000072 4.73036289 [28468] , PlaylistSongs .IdPlaylistSong, PlaylistSongs.SongOrder as PlaySongOrder
00000073 4.73036289 [28468] FROM Songs
00000074 4.73036289 [28468] , PlaylistSongs
00000075 4.73036289 [28468] WHERE Songs .ID=PlaylistSongs .IDSong AND PlaylistSongs.IDPlaylist = 425
00000076 4.73036289 [28468] ORDER BY PlaylistSongs.SongOrder
00000077 4.73077583 [28468] OpenQuery - Preparing query SELECT Songs.*
00000078 4.73077583 [28468] , PlaylistSongs.IdPlaylistSong, PlaylistSongs.SongOrder as PlaySongOrder
00000079 4.73077583 [28468] FROM Songs
00000080 4.73077583 [28468] , PlaylistSongs
00000081 4.73077583 [28468] WHERE Songs .ID=PlaylistSongs.IDSong AND PlaylistSongs.IDPlaylist = 425
00000082 4.73077583 [28468] ORDER BY PlaylistSongs.SongOrder
00000083 4.73105621 [28468] 14464 DB lock finished, took 3809.
00000084 4.73190308 [28468] Going 'FillingThread'to make final callback 14464
00000085 4.73322439 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000086 4.73331261 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000087 4.73346519 [28468] 30568 DB lock finished, took 1821.
00000088 4.73348570 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000089 4.73361969 [28468] 30568 DB lock finished, took 829.
00000090 4.73370600 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000091 4.73379230 [28468] 30568 DB lock finished, took 272.
00000092 4.73387766 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000093 4.73396492 [28468] 30568 DB lock finished, took 618.
00000094 4.73411846 [28468] Events: Invoking event: z9
00000095 4.73419142 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000096 4.73422718 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000097 4.73425961 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000098 4.73460817 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000099 4.73466492 [28468] TEventedAutoObject Created, Class: TSDBTreeNode
00000100 4.73469400 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000101 5.00497007 [16468] Open autolaunch key ok
00000102 5.99982119 [16468] Open autolaunch key ok
00000103 6.99725103 [16468] Open autolaunch key ok
00000104 7.35933924 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000105 7.35942698 [28468] TEventedAutoObject Destroying, Class: TSDBTreeNode
00000106 7.35949516 [28468] Events: Successfully finished event: z9
00000107 7.35989761 [28468] MM4 [30568] TImagePlus.Paint: start
00000108 7.36015272 [28468] MM4 [30568] TImagePlus.Paint: finished
00000109 7.37192011 [28468] 30568 DB open SQL: SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000110 7.37193537 [28468] OpenQuery - Preparing query SELECT IDFilter FROM Playlists WHERE IDPlaylist = 425
00000111 7.37213135 [28468] 30568 DB lock finished, took 1826.
00000112 7.37221766 [28468] 30568 DB open SQL: SELECT * FROM Filters WHERE ID=?
00000113 7.37234020 [28468] 30568 DB lock finished, took 852.
00000114 7.37235498 [28468] 30568 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
00000115 7.37242174 [28468] 30568 DB lock finished, took 65.
00000116 7.37253189 [28468] 30568 DB open SQL: SELECT View FROM PlaylistViews WHERE IDView=?
00000117 7.37262154 [28468] 30568 DB lock finished, took 632.
00000118 7.37289858 [28468] Thread 'FillingThread'has finished 14464
00000119 7.99957514 [16468] Open autolaunch key ok
00000120 8.99769115 [16468] Open autolaunch key ok
00000121 9.99814129 [16468] Open autolaunch key ok