Playlists slow to load

Get answers about using MediaMonkey 4 for Windows.

Moderator: Gurus

markproxy
Posts: 5
Joined: Fri Feb 26, 2021 4:40 pm

Playlists slow to load

Post by markproxy »

I've been a happy MM user for many years. Just recently, I started to notice that my playlists are very slow to load. I click on the playlist, wait a few seconds, the list of songs goes blank, I wait a few more seconds, and then the tracks in the playlist all show up. This happens consistently every time I switch playlists. I tried optimizing the database (both quick & complete). I updated to the latest version of MM (4.1.31.1919). Nothing has helped so far. I'm on a very capable Windows 10 machine, and have never had performance issues with MM before.

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	
Lowlander
Posts: 56465
Joined: Sat Sep 06, 2003 5:53 pm
Location: MediaMonkey 5

Re: Playlists slow to load

Post by Lowlander »

MediaMonkey 5 should be faster.

You may want to run File > Maintain Library with complete optimization checked. This can improve performance.
markproxy
Posts: 5
Joined: Fri Feb 26, 2021 4:40 pm

Re: Playlists slow to load

Post by markproxy »

Thanks, I already tried complete database optimization. That didn't help. This is something that went from almost instantaneous to taking upwards of 8 seconds, and I can't point to any change that might have triggered the performance degradation.
markproxy
Posts: 5
Joined: Fri Feb 26, 2021 4:40 pm

Re: Playlists slow to load

Post by markproxy »

The Magic Nodes extension turned out to be the cause of slowness. I attempted to uninstall via Tools -> Extensions and it froze the app every time, so I manually removed it, and once it was gone my playlists started loading instantly again. It's a shame, as I really like the power to explore my music that Magic Nodes provides, and it worked fine for many months... but not being able to jump quickly from playlist to playlist made it not-worth-it.
Post Reply