MM stops playing when starting new song

Get answers about using the current release of MediaMonkey for Windows.

Moderator: Gurus

sweeney7bb

MM stops playing when starting new song

Post by sweeney7bb » Thu Mar 01, 2018 7:10 pm

Hello!

This is a weird, intermittent issue. there's random tracks that mediamonkey will stop playback on when they load, sometimes as much as 10-15 seconds into the tracks.

I've tried turning off all crossfading, plugins, etc and the issue persists. Seems to happen on various formats / bitrates, etc.

My PC specs are:

Windows 10 pro
Processor: Intel(R) Core(TM) i5-3470 CPU @ 3.20GHz (4 CPUs), ~3.2GHz
Memory: 16384MB RAM
Video Card: NVIDIA GeForce GTX 750 Ti
Sound Card: ASUS Xonar DSX

I ran MM in debug and captured output from both a normal song and a song that stops.

first the working song:

Code: Select all

[845480] Saving of Now Playing selection started
[845480] BQ: Added new task. Currently 1 tasks in queue.
[845480] BQ: Currently running 0 threads of 1
[845480] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 0, All: 34
[845480] TWAPluginEngine.Stop - going to stop input plugin
[845480] Going to stop.
[845480] PrepareDockedVideo: Begin
[845480] PrepareDockedVideo: Reduced window or audio
[845480] PrepareDockedVideo: End
[845480] Thread 'BackgroundQueue' has started 850492
[845480] OpenQuery - Preparing query 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 
[845480] 850492 DB lock finished, took 55151.
[845480] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 0, All: 35
[845480] Player: Setting current track: D:\Users\sween\Music\MediaMonkey\_flac\The Offspring\[1994] Smash\06 Something to Believe In [1006 kbps flac].flac
[845480] PrepareDockedVideo: Switching=false IsStartingPlayback=true WasManuallyChanged=false
[845480] PrepareDockedVideo: End
[845480] Player: _Play() procedure entering.
[845480] Player: W7 icon updated.
[845480] Player: Updating playlist entry.
[845480] Going to play file: D:\Users\sween\Music\MediaMonkey\_flac\The Offspring\[1994] Smash\06 Something to Believe In [1006 kbps flac].flac
[845480] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 1, All: 36
[845480] 927716 DB open SQL: SELECT Lyrics, Comment FROM Songs WHERE Songs.ID=? 
[845480] MM4 [927716] TLyricsScroller.createOverlay: rows: 6
[845480] MM4 [844568] UpdateSongPictures: D:\Users\sween\Music\MediaMonkey\_flac\The Offspring\[1994] Smash\06 Something to Believe In [1006 kbps flac].flac
[845480] GetFileInfo: D:\Users\sween\Music\MediaMonkey\_flac\The Offspring\[1994] Smash\06 Something to Believe In [1006 kbps flac].flac
[845480] Winamp Playlist sim - WM_USER message 6
[845480] Winamp Playlist sim - WM_USER message 1073741830
[845480] FLAC: FORMAT_DecodeInit().
[845480] FLAC: Calling FLAC__stream_decoder_init_stream().
[845480] FLAC: StreamDecoderMetadataCallback.
[845480] FH.ReadFileInfo openned
[845480] Added Covers: 2
[845480] f_flac: FORMAT_CloseFile
[845480] MM4 [927716] TImagePlus.Paint: start
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] Thread 'anonymous' has started 226244
[845480] Added Cover Lists: 2
[845480] GetFileInfo: readPictures=spAlways
[845480] f_flac: FORMAT_OpenFile
[845480] Added Covers: 5
[845480] 226244 DB prepare SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=?
[845480] 226244 DB open SQL: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 
[845480] GetFileInfo: Found in DB
[845480] 226244 DB open SQL: SELECT * FROM Covers WHERE IDSong=37390 ORDER BY CoverOrder 
[845480] 226244 DB lock finished, took 593.
[845480] Remaining Covers: 5
[845480] Added Covers: 7
[845480] MM4 [927716] TStretcher.PerformStretch: Threads[2], source[75, 75], target[160,160]
[845480] BQ: Starting a new task.
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - Cover should be loaded
[845480] Win7Tools - Thumbnail - Loading cover
[845480] BQ: Added new task. Currently 1 tasks in queue.
[845480] BQ: There are threads waiting, resume one.
[845480] BQ: Starting a new task.
[845480] BQ: Starting a new thread.
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 5, All: 39
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MM4 [927716] TStretcher.PerformStretch: Finished
[845480] Remaining Covers: 7
[845480] Remaining Covers: 5
[845480] DB: Deleting prepared query: SELECT Songs.* FROM Songs WHERE Songs.IDMedia=? AND Songs.SongPath=? 
[845480] MMDS: Written 101376 bytes
[845480] Remaining Covers: 3
[845480] BQ: Starting a new task.
[845480] MMDS: Written 101376 bytes
[845480] MM4 [927716] TImagePlus.Paint: finished
[845480] MMDS: Written 101376 bytes
[845480] Keep only 5 previous songs:
[845480] Saving of Now Playing selection started
[845480] Saving of Now Playing selection finished
[845480] RestoreNowPlayingSelection: PlayerLock locked
[845480] RestoreNowPlayingSelection: PlayerLock unlocked
[845480] Auto DJ is performing
[845480] OpenQuery - Preparing query  Select Playlists.* from Playlists WHERE IDPlaylist=63  
[845480] 395276 DB lock finished, took 1000.
[845480] 395276 DB query prepare finished, took 111.
[845480] 395276 DB prepare SQL: SELECT ID, Name, View FROM Views WHERE ID = ?
[845480] 395276 DB open SQL: SELECT ID, Name, View FROM Views WHERE ID = ? 
[845480] 395276 DB open SQL: SELECT Songs.* 
[845480]  FROM Songs WHERE ((Songs.Rating>=96 and Songs.Rating<=100) OR (Songs.Rating>=86 and Songs.Rating<=95) OR (Songs.Rating>=76 and Songs.Rating<=85) OR (Songs.Rating>=66 and Songs.Rating<=75) OR (Songs.Rating>=56 and Songs.Rating<=65) OR Songs.Rating<0) AND (upperw(Songs.Extension) <> upperw('m4p')) AND Songs.LastTimePlayed<43159.2083333333 AND  Songs.TrackType in (0,4,3)  ORDER BY Random(*)  
[845480] MM4 [927716] TStretcher.PerformStretch: Threads[4], source[500, 500], target[167,167]
[845480] BQ: Starting a new task.
[845480] TGenThread.Create: ThreadName: BackgroundQueue , Running Threads: Internal: 5, All: 39
[845480] MM4 [927716] TStretcher.PerformStretch: Finished
[845480] Thread 'BackgroundQueue' has started 863372
[845480] PrepareDockedVideo: Begin
[845480] PrepareDockedVideo: Reduced window or audio
[845480] PrepareDockedVideo: End
[845480] MM4 [927716] TImagePlus.Paint: start
[845480] MM4 [927716] TImagePlus.Paint: start
[845480] MM4 [927716] TLyricsScroller.createOverlay: finished
[845480] MM4 [927716] TLyricsScroller.createOverlay: finished
[845480] MM4 [927716] TImagePlus.Paint: finished
[845480] Win7Tools - Thumbnail - add rating stars
[845480] Win7Tools - Thumbnail - add rating stars
[845480] BQ: Added new task. Currently 1 tasks in queue.
[845480] BQ: There are threads waiting, resume one.
[845480] 395276 DB lock finished, took 788017.
[845480] UpdateLastModifiedForAutoPlaylist started, idPlaylist = 63 Tracks count = 20
[845480] OpenQuery - Preparing query SELECT LastContentHash, LastModified FROM Playlists WHERE IDPlaylist = 63 
[845480] 395276 DB exec SQL: UPDATE Playlists SET LastContentHash = 'NZI1PI92MH711U3P', LastModified = '2018-03-01 23:51:50' WHERE IDPlaylist = 63 
[845480] 395276 DB lock finished, took 26929.
[845480] OpenQuery - Preparing query SELECT * FROM Playlists WHERE IDPlaylist=63 
[845480] UpdateLastModifiedForAutoPlaylist finished, idPlaylist = 63 Oldhash = 7B5SP6HHGFJHRG5J Newhash = NZI1PI92MH711U3P
[845480] Player: Adding track D:\Users\sween\Music\MediaMonkey\_flac\Various Artists\[0000] The Sixties Collection Volume 1\05 He's So Fine [783 kbps flac].flac
[845480] TGenThread.Create: ThreadName: PlayerEntriesUpdater_Var , Running Threads: Internal: 7, All: 39
[845480] Auto DJ: Performing finished
[845480] DB: Deleting prepared query: SELECT * FROM Filters WHERE ID=? 
[845480] Thread 'PlaybackStarter_Var'has finished 395276
[845480] Thread 'PlayerEntriesUpdater_Var' has started 847420
[845480] Going 'PlayerEntriesUpdater_Var'to make final callback 847420
[845480] Thread 'IsSongAccessibleAsync'has finished 865284
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[3880] 138364 0000000000000000 ENTER: DllCanUnloadNow
[845480] MMDS: Written 101376 bytes
[845480] Going 'BackgroundQueue'to make final callback 858420
[845480] Going 'BackgroundQueue'to make final callback 863372
[845480] Going 'BackgroundQueue'to make final callback 850492
[845480] Going 'BackgroundQueue'to make final callback 844568
[845480] Thread 'BackgroundQueue'has finished 844568
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 1, All: 32
[845480] PostponeComputerSleep: Enabled = 0
[845480] Thread 'UpdateDriveLetters_Var' has started 903456
[845480] >UpdateDriveLetters - end<
[845480] Thread 'UpdateDriveLetters_Var'has finished 903456
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: ***Playback pointer crossed buffer end***
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] MMDS: ***Playback pointer crossed buffer end***
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Written 101376 bytes
[845480] Win7Tools - Thumbnail - add rating stars
[845480] MMDS: Written 101376 bytes
[845480] MMDS: Pause starting
[845480] MMDS: Pause finished
[845480] PrepareDockedVideo: Begin
[845480] PrepareDockedVideo: Reduced window or audio
[845480] PrepareDockedVideo: End
and now the one that stops:

Code: Select all

[19284] Lizard Mode: Priviledged process
[19284] Lizard Mode: Reverting to default M/KB Configuration
[845480] MM4 [927716] TImagePlus.Paint: start
[845480] Saving of Now Playing selection started
[845480] SaveNowPlayingSelection: PlayerLock unlocked
[845480] PrepareDockedVideo: Switching=false IsStartingPlayback=false WasManuallyChanged=false
[845480] PrepareDockedVideo: End
[845480] TGenThread.Create: ThreadName: PlaybackStarter_Var , Running Threads: Internal: 0, All: 31
[845480] Thread 'PlaybackStarter_Var' has started 886900
[845480] Player: W7 icon updated.
[845480] Player: Setting current track: D:\Users\sween\Music\MediaMonkey\_flac\Nightwish\[2007] Dark Passion Play\12 7 Days to the Wolves [1032 kbps flac].flac
[845480] Player: Song is not cached, try to play the original file
[845480] Player: Playback started
[845480] Winamp Playlist sim - WM_USER message 5
[845480] Winamp Playlist sim - WM_USER message 1073741829
[845480] Going 'PlaybackStarter_Var'to make final callback 886900
[845480] Thread 'PlaybackStarter_Var'has finished 886900
[845480] PrepareDockedVideo: Reduced window or audio
[845480] PrepareDockedVideo: End
[3880] 8916 0000000000000000 ENTER: DllCanUnloadNow
[845480] TGenThread.Create: ThreadName: UpdateDriveLetters_Var , Running Threads: Internal: 0, All: 31
[845480] PostponeComputerSleep: Enabled = 0
[845480] >UpdateDriveLetters - start<
[845480] >UpdateDriveLetters - end<
[845480] Thread 'UpdateDriveLetters_Var'has finished 357724

Lowlander
Posts: 44734
Joined: Sat Sep 06, 2003 5:53 pm
Location: MediaMonkey 5

Re: MM stops playing when starting new song

Post by Lowlander » Thu Mar 01, 2018 7:19 pm

Which version of MediaMonkey?
Lowlander (MediaMonkey user since 2003)

patricks79
Posts: 6
Joined: Tue Mar 15, 2016 1:43 pm

Re: MM stops playing when starting new song

Post by patricks79 » Thu Mar 01, 2018 7:28 pm

Lowlander wrote:Which version of MediaMonkey?
4.1.20.1864
Gold License

Peke
Posts: 11203
Joined: Tue Jun 10, 2003 7:21 pm
Location: Serbia
Contact:

Re: MM stops playing when starting new song

Post by Peke » Thu Mar 01, 2018 9:03 pm

Hi,
Have you tried to change Output plugin to/from WASAPI and see if playback acts same?

Have you tried to reinstall Drivers https://www.asus.com/us/Sound-Cards/Xonar_DSX/HelpDesk/ ?

What is your system output setting I've found out that going above 24/96 require very high end to see full benefit of Hi-Res?

Also it would be valuable to open support ticket and supply us with full debug log from MMW start to close.
Best regards,
Pavle
MediaMonkey lead QA/Tech Support guru
Admin of Free MediaMonkey addon Site HappyMonkeying
Image
Image
How to add Images to forum

patricks79
Posts: 6
Joined: Tue Mar 15, 2016 1:43 pm

Re: MM stops playing when starting new song

Post by patricks79 » Thu Mar 01, 2018 9:20 pm

Peke wrote:Hi,
Have you tried to change Output plugin to/from WASAPI and see if playback acts same?

Have you tried to reinstall Drivers https://www.asus.com/us/Sound-Cards/Xonar_DSX/HelpDesk/ ?

What is your system output setting I've found out that going above 24/96 require very high end to see full benefit of Hi-Res?

Also it would be valuable to open support ticket and supply us with full debug log from MMW start to close.
the forum did a weird thing with my user name, i made the original post

I'll try the first two

i'm not sure what that 3rd one is - the system setting and 24/96

if those don't work i'll do the 4th

thank you!

patricks79
Posts: 6
Joined: Tue Mar 15, 2016 1:43 pm

Re: MM stops playing when starting new song

Post by patricks79 » Mon Mar 05, 2018 12:16 pm

patricks79 wrote:
Peke wrote:Hi,
Have you tried to change Output plugin to/from WASAPI and see if playback acts same?

Have you tried to reinstall Drivers https://www.asus.com/us/Sound-Cards/Xonar_DSX/HelpDesk/ ?

What is your system output setting I've found out that going above 24/96 require very high end to see full benefit of Hi-Res?

Also it would be valuable to open support ticket and supply us with full debug log from MMW start to close.
i changed it to WASAPI and it got worse, the entire program froze within seconds of playback.

i switched back to default, but went into audio properties and changed the format from 16 bit, 48 khz to 16 bit 44.1 khz (still not sure what your 24/96 meant) and that seems to have corrected. going to be testing today.

Peke
Posts: 11203
Joined: Tue Jun 10, 2003 7:21 pm
Location: Serbia
Contact:

Re: MM stops playing when starting new song

Post by Peke » Mon Mar 05, 2018 4:47 pm

This more and more looks like you have driver issue, rather than MMW. as WASAPI uses direct access to sound card thru driver, so if it makes things worse there must be another element making issues.
Best regards,
Pavle
MediaMonkey lead QA/Tech Support guru
Admin of Free MediaMonkey addon Site HappyMonkeying
Image
Image
How to add Images to forum

Post Reply