MM4 Issues playing song from playlist

Beta Testing for Windows Products and plugins

Moderator: Gurus

djg0817
Posts: 1
Joined: Tue Nov 08, 2011 1:10 pm

MM4 Issues playing song from playlist

Post by djg0817 »

I am using MM4 RC 4. When I try to play a song from a playlist, the list randomly scrolls the songs, and never selects one to play. Below is the debug info. I had this issue on another releases of MM4, but finally had a chance to send debug info. MM3 does not have this issue for me.


Thanks.


[\\ASUSLAPTOP]

Code: Select all

00007114	90.80057526	[8084] PrepareDockedVideo: Begin
00007115	90.80071259	[8084] _Stop: finished
00007116	90.80126190	[8084] Player: Next track prepared, going to start playback
00007117	90.80144501	[8084] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
00007118	90.80153656	[8084] BQ: Added new task. Currently 1 tasks in queue.
00007119	90.80159760	[8084] BQ: There are threads waiting, resume one.
00007120	90.81114197	[8084] Winamp simulation window received messsage 12
00007121	90.81121063	[8084] Winamp sim - message processing finished
00007122	90.81126404	[8084] BQ: Starting a new task.
00007123	90.81723022	[8084] MP3.ReadHeader - Found ID3v2, length: 5715
00007124	90.82659912	[8084] MP3.ReadHeader finished
00007125	90.82924652	[8084] PrepareDockedVideo: Begin
00007126	90.82930756	[8084] PrepareDockedVideo: Reduced window or audio
00007127	90.82949066	[8084] PrepareDockedVideo: End
00007128	90.83435059	[8084] MP3: Going to read tag from D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007129	90.83770752	[8084] MP3: Tag read from D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007130	90.83798218	[8084] 8608 DB lock finished, took 353.
00007131	90.83815002	[8084] UpdateSongPictures: CoverList.LoadForSongId, D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007132	90.83837891	[8084] Player: Going to handle the current track.
00007133	90.83843231	[8084] Player: Updating playlist entry.
00007134	90.83848572	[8084] Player: Track entry prepared, going to start playback.
00007135	90.83859253	[8084] Going to play file: D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007136	90.83867645	[8084] Going to start playback.
00007137	90.83876038	[8084] INWMP3: Stop() started
00007138	90.83881378	[8084] INWMP3: Close() 1
00007139	90.83885956	[8084] INWMP3: Close() 3
00007140	90.83890533	[8084] INWMP3: StopOutput() started
00007141	90.83896637	[8084] INWMP3: Stop() finished
00007142	90.85375214	[8084] Winamp sim - WM_USER message 603
00007143	90.87529755	[8084] GetFileInfo: D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007144	90.87551117	[8084] MP3.ReadHeader - File size 7344621
00007145	90.87557983	[8084] MP3.ReadHeader framestarts: 5725, 5725
00007146	90.87566376	[8084] MP3: Going to read tag from D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007147	90.87885284	[8084] MP3: Tag read from D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007148	90.88136292	[8084] INWMP3: StripID3Stream: Openning TStreamAccess
00007149	90.88143921	[8084] Locked file: D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007150	90.88151550	[8084] Unlocked file: D:\WAV2MP3\Caedmon's Call_40 Acres_5_Where I Began.mp3
00007151	90.88157654	[8084] INWMP3: StreamSeek 2 - 0
00007152	90.88165283	[8084] INWMP3: StripID3Stream: ID3 found and skipped.
00007153	90.88169861	[8084] INWMP3: StripID3Stream: Looking for data.
00007154	90.88175964	[8084] INWMP3: StripID3Stream: Open finished.
00007155	90.88184357	[8084] INWMP3: StreamSeek 0 - 0
00007156	90.88280487	[8084] INWMP3: OnStatus 1.
00007157	90.88295746	[8084] INWMP3: Open() - Stream starting.
00007158	90.88361359	[8084] WASAPI: Output thread is starting
00007159	90.88429260	[8084] WASAPI: Default device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007160	90.88476563	[8084] WASAPI: Best format for playback found: 44100kHz, 2 ch, 16 bps
00007161	90.88481903	[8084] WASAPI: New track not starting, finishing playback thread.
00007162	90.88490295	[8084] INWMP3: Play() problem
00007163	90.88494873	[8084] INWMP3: Stop() started
00007164	90.88501740	[8084] INWMP3: StopStream() started
00007165	90.88511658	[8084] INWMP3: OnStatus 13.
00007166	90.88518524	[8084] INWMP3: Close() 1
00007167	90.88558960	[8084] INWMP3: Close() 3
00007168	90.88565063	[8084] INWMP3: StopOutput() started
00007169	90.88569641	[8084] INWMP3: Stop() finished
00007170	90.88578796	[8084] Decoder: CreateDecoder() started
00007171	90.88584137	[8084] Player: There is a problem in playing this file, try the next one
00007172	90.88587952	[8084] Player: Next command started
00007173	90.88591003	[8084] _Stop: started
00007174	90.88600922	[8084] Winamp sim - WM_USER message 603
00007175	90.90078735	[8084] 6928 DB open SQL: SELECT Playlists.IDPlaylist, Playlists.PlaylistName FROM Playlists WHERE   Playlists.IDPlaylist IN    (SELECT PlaylistSongs.IDPlaylist FROM PlaylistSongs WHERE      PlaylistSongs.IDSong IN        (SELECT ID FROM Songs ORDER BY LastTimePlayed DESC LIMIT 200)          GROUP BY PlaylistSongs.IDPlaylist) LIMIT 10 
00007176	90.90177155	[8084] 6928 DB lock finished, took 1844.
00007177	91.01396179	[8084] PrepareDockedVideo: Begin
00007178	91.01406097	[8084] _Stop: finished
00007179	91.01460266	[8084] Player: Next track prepared, going to start playback
00007180	91.01483917	[8084] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
00007181	91.01490021	[8084] BQ: Added new task. Currently 1 tasks in queue.
00007182	91.01495361	[8084] BQ: There are threads waiting, resume one.
00007183	91.02479553	[8084] Winamp simulation window received messsage 12
00007184	91.02486420	[8084] Winamp sim - message processing finished
00007185	91.02492523	[8084] BQ: Starting a new task.
00007186	91.02981567	[8084] MP3.ReadHeader - Found ID3v2, length: 2118
00007187	91.03858185	[8084] MP3.ReadHeader finished
00007188	91.03958893	[8084] PrepareDockedVideo: Switching=false IsStartingPlayback=true WasManuallyChanged=false
00007189	91.03964233	[8084] PrepareDockedVideo: Audio
00007190	91.03977966	[8084] Player: _Play() procedure entering.
00007191	91.04683685	[8084] MP3: Going to read tag from D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007192	91.04716492	[8084] Player: Going to handle the current track.
00007193	91.04725647	[8084] Player: Setting current track: D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007194	91.04730988	[8084] Player: Song is not cached, try to play the original file
00007195	91.04744720	[8084] Plugin C:\Program Files (x86)\MediaMonkey4\Plugins\in_wmp3.dll can play D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007196	91.04750824	[8084] TWAInputPlugin.Play starting
00007197	91.04755402	[8084] INWMP3: Close() started
00007198	91.04760742	[8084] INWMP3: Close() 2
00007199	91.04766083	[8084] INWMP3: Close() finished
00007200	91.04771423	[8084] INWMP3: StopOutput() finished
00007201	91.04929352	[8084] MP3: Tag read from D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007202	91.04955292	[8084] 8712 DB lock finished, took 329.
00007203	91.04969788	[8084] UpdateSongPictures: CoverList.LoadForSongId, D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007204	91.04990387	[8084] 8712 DB lock finished, took 269.
00007205	91.07364655	[8084] Winamp sim - WM_USER message 603
00007206	91.07375336	[8084] Winamp sim - WM_USER message 603
00007207	91.09918213	[8084] GetFileInfo: D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007208	91.09938812	[8084] MP3.ReadHeader - File size 11485312
00007209	91.09946442	[8084] MP3.ReadHeader framestarts: 2128, 2128
00007210	91.09955597	[8084] MP3: Going to read tag from D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007211	91.10186005	[8084] INWMP3: Open()
00007212	91.10227966	[8084] Locking file: D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007213	91.10237122	[8084] Unlocking file: D:\WAV2MP3\George Michael_Two Rooms- Celebrating the Songs of Elton John & Bernie Taupin_Tonight_16.mp3
00007214	91.10242462	[8084] INWMP3: StripID3Stream: SetID3Offset().
00007215	91.10248566	[8084] INWMP3: StreamSeek 0 - 0
00007216	91.10256195	[8084] INWMP3: StreamSeek 0 - 0
00007217	91.10261536	[8084] INWMP3: StreamSeek 0 - 0
00007218	91.10270691	[8084] INWMP3: StreamSeek 0 - 0
00007219	91.10287476	[8084] INWMP3: StreamSeek 0 - 11483056
00007220	91.10359192	[8084] INWMP3: Open() - Stream openned.
00007221	91.10369873	[8084] WASAPI: MMOpen started.
00007222	91.10450745	[8084] WASAPI: Looking for device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007223	91.10515594	[8084] WASAPI: Found device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007224	91.10555267	[8084] WASAPI: No output format for automatic exclusive mode was found.
00007225	91.10561371	[8084] WASAPI: MMOpen finished.
00007226	91.10566711	[8084] INWMP3: Stop() started
00007227	91.10572815	[8084] WASAPI: Output thread is terminating
00007228	91.10578918	[8084] INWMP3: StopStream() finished
00007229	91.10591888	[8084] WASAPI: Output thread is terminated
00007230	91.10604858	[8084] INWMP3: Close() 2
00007231	91.10646820	[8084] INWMP3: Close() finished
00007232	91.10650635	[8084] INWMP3: StopOutput() finished
00007233	91.10658264	[8084] TWAInputPlugin.Play finishing
00007234	91.10667419	[8084] Winamp Playlist sim - WM_USER message 3245
00007235	91.10671234	[8084] Winamp Playlist sim - message left to the default processing
00007236	91.10675812	[8084] Winamp Playlist sim - WM_USER message 1073745069
00007237	91.10679626	[8084] Winamp Playlist sim - message left to the default processing
00007238	91.10694885	[8084] Winamp sim - WM_USER message 603
00007239	91.12178040	[8084] 6928 DB open SQL: SELECT Playlists.IDPlaylist, Playlists.PlaylistName FROM Playlists WHERE   Playlists.IDPlaylist IN    (SELECT PlaylistSongs.IDPlaylist FROM PlaylistSongs WHERE      PlaylistSongs.IDSong IN        (SELECT ID FROM Songs ORDER BY LastTimePlayed DESC LIMIT 200)          GROUP BY PlaylistSongs.IDPlaylist) LIMIT 10 
00007240	91.23732758	[8084] PrepareDockedVideo: Begin
00007241	91.23743439	[8084] _Stop: finished
00007242	91.23797607	[8084] Player: Next track prepared, going to start playback
00007243	91.23819733	[8084] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
00007244	91.23826599	[8084] BQ: Added new task. Currently 1 tasks in queue.
00007245	91.23831940	[8084] BQ: There are threads waiting, resume one.
00007246	91.24478149	[8084] MP3.ReadHeader - Found ID3v2, length: 877
00007247	91.24739075	[8084] Winamp simulation window received messsage 12
00007248	91.24746704	[8084] Winamp sim - message processing finished
00007249	91.24752045	[8084] BQ: Starting a new task.
00007250	91.31856537	[8084] GetFileInfo: D:\WAV2MP3\Wilderness of Tekoa_Nobody's Watching Me_Take it All_3.mp3
00007251	91.31878662	[8084] MP3.ReadHeader - File size 10950784
00007252	91.31886292	[8084] MP3.ReadHeader framestarts: 887, 887
00007253	91.31895447	[8084] MP3: Going to read tag from D:\WAV2MP3\Wilderness of Tekoa_Nobody's Watching Me_Take it All_3.mp3
00007254	91.32132721	[8084] INWMP3: Open()
00007255	91.32189941	[8084] Locking file: D:\WAV2MP3\Wilderness of Tekoa_Nobody's Watching Me_Take it All_3.mp3
00007256	91.32201385	[8084] Unlocking file: D:\WAV2MP3\Wilderness of Tekoa_Nobody's Watching Me_Take it All_3.mp3
00007257	91.32209778	[8084] INWMP3: StripID3Stream: SetID3Offset().
00007258	91.32218933	[8084] INWMP3: StreamSeek 0 - 0
00007259	91.32228851	[8084] INWMP3: StreamSeek 0 - 0
00007260	91.32237244	[8084] INWMP3: StreamSeek 0 - 0
00007261	91.32253265	[8084] INWMP3: StreamSeek 0 - 0
00007262	91.32272339	[8084] INWMP3: StreamSeek 0 - 10949769
00007263	91.32344818	[8084] INWMP3: Open() - Stream openned.
00007264	91.32360077	[8084] WASAPI: MMOpen started.
00007265	91.32442474	[8084] WASAPI: Looking for device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007266	91.32501221	[8084] WASAPI: Found device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007267	91.32534027	[8084] WASAPI: No output format for automatic exclusive mode was found.
00007268	91.32539368	[8084] WASAPI: MMOpen finished.
00007269	91.32545471	[8084] INWMP3: Stop() started
00007270	91.32550049	[8084] INWMP3: Close() started
00007271	91.32555389	[8084] INWMP3: StopStream() finished
00007272	91.32566833	[8084] INWMP3: Close() 1
00007273	91.32576752	[8084] WASAPI: Output thread is terminated
00007274	91.32620239	[8084] INWMP3: Close() finished
00007275	91.32624817	[8084] INWMP3: StopOutput() finished
00007276	91.32632446	[8084] TWAInputPlugin.Play finishing
00007277	91.32639313	[8084] Winamp Playlist sim - WM_USER message 11447
00007278	91.32643127	[8084] Winamp Playlist sim - message left to the default processing
00007279	91.32648468	[8084] Winamp Playlist sim - WM_USER message 1073753271
00007280	91.32656860	[8084] Winamp Playlist sim - message left to the default processing
00007281	91.32672882	[8084] Winamp sim - WM_USER message 603
00007282	91.34213257	[8084] 6928 DB open SQL: SELECT Playlists.IDPlaylist, Playlists.PlaylistName FROM Playlists WHERE   Playlists.IDPlaylist IN    (SELECT PlaylistSongs.IDPlaylist FROM PlaylistSongs WHERE      PlaylistSongs.IDSong IN        (SELECT ID FROM Songs ORDER BY LastTimePlayed DESC LIMIT 200)          GROUP BY PlaylistSongs.IDPlaylist) LIMIT 10 
00007283	91.45558929	[8084] PrepareDockedVideo: Begin
00007284	91.45569611	[8084] _Stop: finished
00007285	91.45623779	[8084] Player: Next track prepared, going to start playback
00007286	91.45644379	[8084] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
00007287	91.45652008	[8084] BQ: Added new task. Currently 1 tasks in queue.
00007288	91.45659637	[8084] BQ: There are threads waiting, resume one.
00007289	91.46599579	[8084] Winamp simulation window received messsage 12
00007290	91.46605682	[8084] Winamp sim - message processing finished
00007291	91.46611786	[8084] BQ: Starting a new task.
00007292	91.47566986	[8084] MP3.ReadHeader - Found ID3v2, length: 1710
00007293	91.48332214	[8084] PrepareDockedVideo: Switching=false IsStartingPlayback=true WasManuallyChanged=false
00007294	91.48337555	[8084] PrepareDockedVideo: Audio
00007295	91.48353577	[8084] Player: _Play() procedure entering.
00007296	91.48637390	[8084] MP3.ReadHeader finished
00007297	91.49118805	[8084] Player: W7 icon updated.
00007298	91.49126434	[8084] Player: Updating playlist entry.
00007299	91.49131775	[8084] Player: Track entry prepared, going to start playback.
00007300	91.49143982	[8084] Going to play file: D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007301	91.49151611	[8084] Going to start playback.
00007302	91.49156952	[8084] INWMP3: Stop() started
00007303	91.49161530	[8084] INWMP3: Close() 1
00007304	91.49166870	[8084] INWMP3: Close() 3
00007305	91.49170685	[8084] INWMP3: StopOutput() started
00007306	91.49175262	[8084] INWMP3: Stop() finished
00007307	91.49468994	[8084] MP3: Tag read from D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007308	91.49492645	[8084] 8608 DB lock finished, took 288.
00007309	91.49507141	[8084] UpdateSongPictures: CoverList.LoadForSongId, D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007310	91.49527740	[8084] 8608 DB lock finished, took 269.
00007311	91.50823975	[8084] Winamp sim - WM_USER message 603
00007312	91.50834656	[8084] Winamp sim - WM_USER message 603
00007313	91.53437805	[8084] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 6, All: 47
00007314	91.53778839	[8084] Thread 'UpdateDriveLetters_Var' has started 14812
00007315	91.53786469	[8084] Thread initialized 14812
00007316	91.54212189	[8084] CDPlayer.ini location: C:\Users\Dan\AppData\Roaming\MediaMonkey\CDPlayer.ini
00007317	91.54255676	[8084] Thread 'UpdateDriveLetters_Var'has finished 14812
00007318	91.55745697	[8084] GetFileInfo: D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007319	91.55765533	[8084] MP3.ReadHeader - File size 8689792
00007320	91.55772400	[8084] MP3.ReadHeader framestarts: 1720, 1720
00007321	91.55780792	[8084] MP3: Going to read tag from D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007322	91.56024933	[8084] INWMP3: Open()
00007323	91.56062317	[8084] Locking file: D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007324	91.56070709	[8084] Unlocking file: D:\WAV2MP3\Toad The Wet Sprocket_Fear_Pray Your Gods_6.mp3
00007325	91.56076813	[8084] INWMP3: StripID3Stream: SetID3Offset().
00007326	91.56082153	[8084] INWMP3: StreamSeek 0 - 0
00007327	91.56089020	[8084] INWMP3: StreamSeek 0 - 0
00007328	91.56094360	[8084] INWMP3: StreamSeek 0 - 0
00007329	91.56104279	[8084] INWMP3: StreamSeek 0 - 0
00007330	91.56121063	[8084] INWMP3: StreamSeek 0 - 8687944
00007331	91.56201172	[8084] INWMP3: Open() - Stream openned.
00007332	91.56212616	[8084] WASAPI: MMOpen started.
00007333	91.56290436	[8084] WASAPI: Looking for device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007334	91.56340790	[8084] WASAPI: Found device: {0.0.0.00000000}.{f5cb4209-6ecd-4d07-9cf2-9e9feeeafc46}
00007335	91.56372070	[8084] WASAPI: No output format for automatic exclusive mode was found.
00007336	91.56376648	[8084] WASAPI: MMOpen finished.
00007337	91.56383514	[8084] WASAPI: Output thread is terminating
00007338	91.56387329	[8084] INWMP3: Close() started
00007339	91.56392670	[8084] INWMP3: StopStream() finished
00007340	91.56406403	[8084] WASAPI: Output thread is terminated
00007341	91.56440735	[8084] INWMP3: Close() 2
00007342	91.56459045	[8084] INWMP3: Close() finished
00007343	91.56463623	[8084] INWMP3: StopOutput() finished
00007344	91.56472778	[8084] TWAInputPlugin.Play finishing
00007345	91.56479645	[8084] Player: There is a problem in playing this file, try the next one
00007346	91.56484985	[8084] _Stop: started
00007347	91.56614685	[8084] Winamp Playlist sim - message left to the default processing
00007348	91.56623840	[8084] Winamp Playlist sim - message left to the default processing
00007349	91.56639862	[8084] Winamp sim - WM_USER message 603
00007350	91.57991028	[8084] 6928 DB open SQL: SELECT Playlists.IDPlaylist, Playlists.PlaylistName FROM Playlists WHERE   Playlists.IDPlaylist IN    (SELECT PlaylistSongs.IDPlaylist FROM PlaylistSongs WHERE      PlaylistSongs.IDSong IN        (SELECT ID FROM Songs ORDER BY LastTimePlayed DESC LIMIT 200)          GROUP BY PlaylistSongs.IDPlaylist) LIMIT 10 
00007351	91.69299316	[8084] PrepareDockedVideo: Begin
00007352	91.69308472	[8084] _Stop: finished
00007353	91.69363403	[8084] Player: Next track prepared, going to start playback
00007354	91.69384766	[8084] PrepareDockedVideo: Switching=true IsStartingPlayback=true WasManuallyChanged=false
00007355	91.69392395	[8084] BQ: Added new task. Currently 1 tasks in queue.
00007356	91.69398499	[8084] BQ: There are threads waiting, resume one.
00007357	91.69441986	[8084] MP3.ReadHeader - File size 9470080
00007358	91.70304108	[8084] Winamp simulation window received messsage 12
00007359	91.70314026	[8084] Winamp sim - message processing finished
00007360	91.70323944	[8084] BQ: Starting a new task.
rusty
Posts: 8969
Joined: Tue Apr 29, 2003 3:39 am
Location: Montreal, Canada

Re: MM4 Issues playing song from playlist

Post by rusty »

Hi,

Try switching to the DirectSound output plugin (via Tools > Options > Player > Output plugin).

Also, are you on the latest build (currently 1456)?

-Rusty
Post Reply