Login at Kodi Home

philbell · 2016-09-02, 20:42

I found this in the general/android forums: http://forum.kodi.tv/showthread.php?tid=238681
I'm opening this thread in music because the problem occurs in windows as well.
To summarize the other (short) thread, it seems that if music files are "damaged" kodi is less tolerant than other music players and aborts playback.
I'd like to know if there's a way to make kodi behave more like other popular music players in this respect - MusicBee and Winamp both play files to the end which kodi aborted..

philbell · 2016-09-09, 23:29

You can also reply if you've experienced the same problem Smile

I'm wondering if I'm the only one who's noticed this. If not, I would think that the developers would be interested in making Kodi a competitive music player. In my opinion, it is currently not competitive. On android I now use ES File Explorer to play music on my NAS.

**DaveBlake** · (This post was last modified: 2016-09-10, 10:00 by DaveBlake.)

(2016-09-09, 23:29)philbell Wrote: I would think that the developers would be interested in making Kodi a competitive music player. In my opinion, it is currently not competitive.

Phil, Kodi has a great many users, and very few devs, I doubt if any of us are upset if someone uses a different player. It isn't what motivates me anyway. Unfortunately I know nothing about the player parts either, so I can not help (I am not just being pissy). Kodi does not do what you want - play damaged files - but other players do. Well great, you have found software that does what you want, use that.

Generally "music corner" is a happy place. If a user had com along saying I have these music files that play fully in XXX but abort in Kodi and I don't understand why, providing both example files and a debug log, then I'm pretty sure if a dev with player knowledge would try to find time to have a look (although response may not be immediate). As it is it just sounds like you have unrealistic expectations, and unfortunately no one with the appropriate knowledge has had either the time or the desire to engage with you.

philbell · 2016-09-15, 00:41

Thanks for your reply. This is a log - at the moment I see no way to attach a music file to this message - I'll have to check how to do that.:

18:30:12 T:10256 DEBUG: Loading settings for nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers(nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3)
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
18:30:12 T:10256 DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (3)
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: PAPlayer (3)
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
18:30:12 T:10256 DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
18:30:12 T:10256 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [mp3]
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::Open - setting usetoc to 0 for accurate VBR MP3 seek
18:30:12 T:10256 INFO: ffmpeg[2810]: [mp3] Skipping 0 bytes of junk at 2308.
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
18:30:12 T:10256 DEBUG: ffmpeg[2810]: [mp3] Estimating duration from bitrate, this may be inaccurate
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
18:30:12 T:10256 INFO: ffmpeg[2810]: Input #0, mp3, nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp':
18:30:12 T:10256 INFO: ffmpeg[2810]: Metadata:
18:30:12 T:10256 INFO: ffmpeg[2810]: album_artist : Emerson, Lake & Palmer
18:30:12 T:10256 INFO: ffmpeg[2810]: title : Tank
18:30:12 T:10256 INFO: ffmpeg[2810]: disc : 1/4
18:30:12 T:10256 INFO: ffmpeg[2810]: artist : Emerson Lake and Palmer
18:30:12 T:10256 INFO: ffmpeg[2810]: genre : Progressive Rock
18:30:12 T:10256 INFO: ffmpeg[2810]: track : 11
18:30:12 T:10256 INFO: ffmpeg[2810]: album : The Return Of The Manticore (Disc 1)
18:30:12 T:10256 INFO: ffmpeg[2810]: Duration: 00:06:45.92, start: 0.000000, bitrate: 192 kb/s
18:30:12 T:10256 INFO: ffmpeg[2810]: Stream #0:0: Audio: mp3, 44100 Hz, stereo, s16p, 192 kb/s
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
18:30:12 T:10256 DEBUG: FactoryCodec - Audio: passthrough - Opening
18:30:12 T:10256 DEBUG: FactoryCodec - Audio: passthrough - Failed
18:30:12 T:10256 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:30:12 T:10256 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:30:12 T:10256 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 0
18:30:12 T:10256 DEBUG: Previous line repeats 1 times.
18:30:12 T:10256 INFO: AudioDecoder: File is queued
18:30:12 T:10256 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 0.000000
18:30:12 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:30:12 T:10256 INFO: PAPlayer: Tongue

repareStream - Ready
18:30:12 T:10256 DEBUG: CApplication: Tongue

layFile: OpenFile succeed, play state 1
18:30:12 T:10256 DEBUG: ADDON: Dll Initializing - MilkDrop
18:30:12 T:10256 DEBUG: SECTION:LoadDLL(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:30:12 T:6800 DEBUG: Thread PAPlayer start, auto delete: false
18:30:12 T:6800 DEBUG: PAPlayer: Tongue

rocess - Playback started
18:30:12 T:6800 DEBUG: CApplication::OnPlayBackStarted: play state was 1, starting 0
18:30:12 T:7200 INFO: CActiveAESink::OpenSink - initialize sink
18:30:12 T:7200 DEBUG: CActiveAESink::OpenSink - trying to open device DIRECTSOUND:{6D410573-4CD3-41B2-84A9-7933A5BC5FC3}
18:30:12 T:10256 DEBUG: msg: Preset is not held, loading a random preset. Current is 0
18:30:12 T:10256 DEBUG: msg: CreateDepthStencilSurface with 1680 x 1050
18:30:12 T:10256 DEBUG: msg: Textures created!
18:30:12 T:10256 DEBUG: Calling TransferSettings for: MilkDrop
18:30:12 T:7200 DEBUG: CAESinkDirectSound::Initialize: Using Window handle: 00080912
18:30:12 T:7200 DEBUG: CAESinkDirectSound::Initialize: secondary buffer created
18:30:12 T:7200 DEBUG: CAESinkDirectSound::Initialize: Initializing DirectSound with the following parameters:
18:30:12 T:7200 DEBUG: Audio Device : Digital Output (S/PDIF) (IDT High Definition Audio CODEC)
18:30:12 T:7200 DEBUG: Sample Rate : 44100
18:30:12 T:7200 DEBUG: Sample Format : AE_FMT_FLOAT
18:30:12 T:7200 DEBUG: Bits Per Sample : 32
18:30:12 T:7200 DEBUG: Valid Bits/Samp : 32
18:30:12 T:7200 DEBUG: Channel Count : 2
18:30:12 T:7200 DEBUG: Block Align : 8
18:30:12 T:7200 DEBUG: Avg. Bytes Sec : 352800
18:30:12 T:7200 DEBUG: Samples/Block : 32
18:30:12 T:7200 DEBUG: Format cBSize : 22
18:30:12 T:7200 DEBUG: Channel Layout : FL,FR
18:30:12 T:7200 DEBUG: Channel Mask : 3
18:30:12 T:7200 DEBUG: Frames : 661
18:30:12 T:7200 DEBUG: Frame Samples : 1322
18:30:12 T:7200 DEBUG: Frame Size : 8
18:30:12 T:7200 DEBUG: CActiveAESink::OpenSink - DIRECTSOUND Initialized:
18:30:12 T:7200 DEBUG: Output Device : SPDIF - Digital Output (S/PDIF) (IDT High Definition Audio CODEC)
18:30:12 T:7200 DEBUG: Sample Rate : 44100
18:30:12 T:7200 DEBUG: Sample Format : AE_FMT_FLOAT
18:30:12 T:7200 DEBUG: Channel Count : 2
18:30:12 T:7200 DEBUG: Channel Layout: FL,FR
18:30:12 T:7200 DEBUG: Frames : 661
18:30:12 T:7200 DEBUG: Frame Samples : 1322
18:30:12 T:7200 DEBUG: Frame Size : 8
18:30:12 T:6912 NOTICE: 1Channel: Service: Playback started
18:30:12 T:10256 DEBUG: Visualisation::Start()
18:30:12 T:10256 DEBUG: CGUIInfoManager::SetCurrentSong(nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3)
18:30:12 T:10256 DEBUG: Loading additional tag info for file nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:30:12 T:10256 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:30:12 T:10256 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:30:12 T:7200 DEBUG: CAESinkDirectSound::CheckPlayStatus: Resuming Playback
18:30:12 T:6852 DEBUG: OnInitialize() started
18:30:12 T:6852 DEBUG: OnInitialize() done
18:30:13 T:2888 DEBUG: CMMNotificationClient::OnPropertyValueChanged: Changed device property of {0.0.0.00000000}.{6d410573-4cd3-41b2-84a9-7933a5bc5fc3} is {9855c4cd-df8c-449c-a181-8191b68bd06c}#0
18:30:15 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:30:15 T:10256 DEBUG: Activating window ID: 12006
18:30:15 T:10256 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------
18:30:15 T:10256 DEBUG: FreeVisualisation() started
18:30:15 T:10256 INFO: ADDON: Dll Stopped - MilkDrop
18:30:15 T:10256 DEBUG: SECTION:UnloadDll(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:30:15 T:10256 INFO: ADDON: Dll Destroyed - MilkDrop
18:30:15 T:10256 DEBUG: FreeVisualisation() done
18:30:15 T:10256 DEBUG: ------ Window Init (MusicVisualisation.xml) ------
18:30:15 T:10256 DEBUG: ADDON: Dll Initializing - MilkDrop
18:30:15 T:10256 DEBUG: SECTION:LoadDLL(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:30:15 T:10256 DEBUG: msg: Preset is not held, loading a random preset. Current is 0
18:30:15 T:10256 DEBUG: msg: CreateDepthStencilSurface with 1680 x 1050
18:30:15 T:10256 DEBUG: msg: Textures created!
18:30:15 T:10256 DEBUG: Calling TransferSettings for: MilkDrop
18:30:16 T:10256 DEBUG: Visualisation::Start()
18:30:16 T:6852 DEBUG: OnInitialize() started
18:30:16 T:6852 DEBUG: OnInitialize() done
18:30:16 T:10256 DEBUG: ------ Window Init (MusicOSD.xml) ------
18:30:18 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:30:18 T:10256 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
18:30:18 T:10256 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
18:30:18 T:10256 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
18:30:18 T:6800 DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 305658
18:30:21 T:10256 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
18:30:22 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:30:22 T:10256 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
18:30:22 T:10256 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
18:30:22 T:10256 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
18:30:22 T:6800 DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 314671
18:30:24 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:30:24 T:10256 DEBUG: CAnnouncementManager - Announcement: OnSeek from xbmc
18:30:24 T:10256 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSeek
18:30:24 T:6800 DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 319399
18:30:27 T:10256 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
18:30:31 T:10256 DEBUG: ------ Window Deinit (Pointer.xml) ------
18:30:34 T:10256 DEBUG: ------ Window Deinit (MusicOSD.xml) ------
18:31:13 T:6800 ERROR: ffmpeg[1A90]: [mp3] Header missing
18:31:13 T:6800 ERROR: Previous line repeats 1 times.
18:31:13 T:6800 ERROR: CAudioDecoder: Error while decoding 1
18:31:13 T:6800 INFO: PAPlayer: Tongue

rocessStream - Stream Finished
18:31:13 T:6800 DEBUG: CApplication::OnQueueNextItem: play state was 2, starting 0
18:31:13 T:6800 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:31:13 T:13044 DEBUG: Thread JobWorker start, auto delete: true
18:31:13 T:13044 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::Open - probing detected format [mp3]
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::Open - setting usetoc to 0 for accurate VBR MP3 seek
18:31:13 T:13044 INFO: ffmpeg[32F4]: [mp3] Skipping 0 bytes of junk at 2313.
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::Open - avformat_find_stream_info starting
18:31:13 T:13044 DEBUG: ffmpeg[32F4]: [mp3] Estimating duration from bitrate, this may be inaccurate
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::Open - av_find_stream_info finished
18:31:13 T:13044 INFO: ffmpeg[32F4]: Input #0, mp3, nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp':
18:31:13 T:13044 INFO: ffmpeg[32F4]: Metadata:
18:31:13 T:13044 INFO: ffmpeg[32F4]: album_artist : Emerson, Lake & Palmer
18:31:13 T:13044 INFO: ffmpeg[32F4]: title : Lucky Man
18:31:13 T:13044 INFO: ffmpeg[32F4]: disc : 1/4
18:31:13 T:13044 INFO: ffmpeg[32F4]: artist : Emerson Lake and Palmer
18:31:13 T:13044 INFO: ffmpeg[32F4]: genre : Progressive Rock
18:31:13 T:13044 INFO: ffmpeg[32F4]: track : 12
18:31:13 T:13044 INFO: ffmpeg[32F4]: album : The Return Of The Manticore (Disc 1)
18:31:13 T:13044 INFO: ffmpeg[32F4]: Duration: 00:04:36.75, start: 0.000000, bitrate: 192 kb/s
18:31:13 T:13044 INFO: ffmpeg[32F4]: Stream #0:0: Audio: mp3, 44100 Hz, stereo, s16p, 192 kb/s
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
18:31:13 T:13044 DEBUG: FactoryCodec - Audio: passthrough - Opening
18:31:13 T:13044 DEBUG: FactoryCodec - Audio: passthrough - Failed
18:31:13 T:13044 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
18:31:13 T:13044 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
18:31:13 T:13044 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:13044 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:13044 DEBUG: CDVDDemuxFFmpeg::SeekTime - seek ended up on time 0
18:31:13 T:13044 DEBUG: Previous line repeats 1 times.
18:31:13 T:13044 INFO: AudioDecoder: File is queued
18:31:13 T:13044 DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 0.000000
18:31:13 T:13044 INFO: PAPlayer: Tongue

repareStream - Ready
18:31:13 T:6800 DEBUG: CApplication::OnPlayBackStarted: play state was 2, starting 0
18:31:13 T:10256 DEBUG: CGUIInfoManager::SetCurrentSong(nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3)
18:31:13 T:10256 DEBUG: Loading additional tag info for file nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:10256 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:6852 DEBUG: CActiveAE: Big Grin

iscardStream - audio stream deleted
18:31:13 T:6800 DEBUG: PAPlayer: Tongue

rocessStreams - Stream Freed
18:31:13 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:31:13 T:10256 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:13 T:10256 DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
18:31:13 T:10256 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
18:31:13 T:6912 NOTICE: 1Channel: Service: Playback started
18:31:13 T:13044 DEBUG: CSaveFileStateJob: Big Grin

oWork - Saving file state for audio item nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3
18:31:13 T:13044 DEBUG: CSaveFileStateJob: Big Grin

oWork - Marking audio item nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3 as listened
18:31:13 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:31:13 T:10256 DEBUG: ------ Window Init (MusicOSD.xml) ------
18:31:13 T:10256 DEBUG: ------ Window Init (Pointer.xml) ------
18:31:15 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:31:15 T:6852 DEBUG: CActiveAE: Big Grin

iscardStream - audio stream deleted
18:31:15 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:31:15 T:10256 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:15 T:6800 DEBUG: CApplication::OnPlayBackStopped: play state was 2, starting 0
18:31:15 T:6800 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
18:31:15 T:6800 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
18:31:15 T:6800 DEBUG: Thread PAPlayer 6800 terminating
18:31:15 T:10256 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Deactivate
18:31:15 T:10256 DEBUG: ------ Window Deinit (MusicOSD.xml) ------
18:31:15 T:10256 DEBUG: ------ Window Deinit (MusicVisualisation.xml) ------
18:31:15 T:10256 DEBUG: FreeVisualisation() started
18:31:15 T:10256 INFO: ADDON: Dll Stopped - MilkDrop
18:31:15 T:10256 DEBUG: SECTION:UnloadDll(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:31:15 T:10256 INFO: ADDON: Dll Destroyed - MilkDrop
18:31:15 T:10256 DEBUG: FreeVisualisation() done
18:31:15 T:10256 DEBUG: CGUIWindowManager: Tongue

reviousWindow: Activate new
18:31:15 T:10256 DEBUG: ------ Window Init (MyMusicNav.xml) ------
18:31:15 T:10256 DEBUG: CGUIMediaWindow::GetDirectory (nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/)
18:31:15 T:10256 DEBUG: ParentPath = [nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/]
18:31:15 T:10256 DEBUG: CNFSFile::Open - opened nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/Komodo Island - Rock & Metal.URL
18:31:15 T:10256 DEBUG: CNFSFile::Close closing file nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/Komodo Island - Rock & Metal.URL
18:31:15 T:844 DEBUG: Thread BackgroundLoader start, auto delete: false
18:31:15 T:844 DEBUG: Loading items: 41, directory: nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/ sort method: 0, ascending: true
18:31:15 T:6912 NOTICE: 1Channel: Service: Playback Stopped
18:31:15 T:6912 NOTICE: 1Channel: Service: Resetting...
18:31:15 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:31:16 T:844 DEBUG: Thread BackgroundLoader 844 terminating
18:31:16 T:10256 DEBUG: RetrieveMusicInfo() took 243 msec
18:31:16 T:7624 DEBUG: Thread BackgroundLoader start, auto delete: false
18:31:16 T:10256 DEBUG: ADDON: Dll Initializing - MilkDrop
18:31:16 T:10256 DEBUG: SECTION:LoadDLL(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:31:16 T:10256 DEBUG: msg: Preset is not held, loading a random preset. Current is 0
18:31:16 T:10256 DEBUG: msg: CreateDepthStencilSurface with 1680 x 1050
18:31:16 T:10256 DEBUG: msg: Textures created!
18:31:16 T:10256 DEBUG: Calling TransferSettings for: MilkDrop
18:31:16 T:6852 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
18:31:16 T:10256 DEBUG: Previous line repeats 1 times.
18:31:16 T:10256 DEBUG: Visualisation::Start()
18:31:16 T:7624 DEBUG: Thread BackgroundLoader 7624 terminating
18:31:16 T:13044 DEBUG: CSaveFileStateJob: Big Grin

oWork - Saving file state for audio item nfs://192.168.0.106/nfs/Public/Shared Music/Library/E/ELP/Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/112 - Lucky Man.mp3
18:31:16 T:10256 DEBUG: FreeVisualisation() started
18:31:16 T:10256 INFO: ADDON: Dll Stopped - MilkDrop
18:31:16 T:10256 DEBUG: SECTION:UnloadDll(C:\Program Files (x86)\Kodi\addons\visualization.milkdrop\visualization.milkdrop.dll)
18:31:16 T:10256 INFO: ADDON: Dll Destroyed - MilkDrop
18:31:16 T:10256 DEBUG: FreeVisualisation() done
18:31:16 T:11552 DEBUG: CMusicDatabase::GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview LEFT JOIN albumartistview on albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
18:31:16 T:11552 DEBUG: Previous line repeats 1 times.
18:31:16 T:11552 DEBUG: CMusicDatabase::GetAlbumsByWhere - query took 1 ms
18:31:16 T:1168 DEBUG: Previous line repeats 1 times.
18:31:16 T:1168 DEBUG: CMusicDatabase::GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview LEFT JOIN albumartistview on albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album'
18:31:16 T:1168 DEBUG: Previous line repeats 1 times.
18:31:16 T:1168 DEBUG: CMusicDatabase::GetAlbumsByWhere - query took 1 ms
18:31:19 T:10256 DEBUG: Previous line repeats 1 times.
18:31:19 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:31:19 T:10256 DEBUG: Activating window ID: 10000
18:31:19 T:10256 DEBUG: ------ Window Deinit (MyMusicNav.xml) ------
18:31:19 T:10256 DEBUG: ------ Window Init (Home.xml) ------
18:31:23 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:31:23 T:10256 DEBUG: Activating window ID: 10004
18:31:23 T:10256 DEBUG: ------ Window Deinit (Home.xml) ------
18:31:23 T:10256 DEBUG: ------ Window Init (Settings.xml) ------
18:31:25 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:31:25 T:10256 DEBUG: Activating window ID: 10016
18:31:25 T:10256 DEBUG: ------ Window Deinit (Settings.xml) ------
18:31:25 T:10256 DEBUG: ------ Window Init (SettingsCategory.xml) ------
18:31:30 T:10256 DEBUG: CInputManager: Tongue

rocessMouse: trying mouse action leftclick
18:31:30 T:10256 NOTICE: Disabled debug logging due to GUI setting. Level 0.
18:31:30 T:10256 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"

philbell · 2016-09-15, 00:44

The file is "Emerson, Lake & Palmer - 1993 - The Return Of The Manticore (4 CD) [192]/111 - Tank.mp3" and it aborts playback at 06:08

tkgafs · 2016-10-15, 17:26

I have also experienced this but it never seems to be consistent.

I play most of my music in party mode and occasionally a track will end prematurely and start playing the next one.

but the same track may play completely correctly at another time

It has the look of somebody pressing skip to next track on a remote, even though they haven't

As I said there does not seem to any reason for it, but it definitely happens, for a while i ran full logs to try and capture the issue, but there never seemed to any sign of an issue in them, so I gave up and accepted, things happen live with it

**Martijn** · 2016-10-15, 17:30

We need full Debug Log as we hate logsnippets (certainly directly posted on the forum)

jeanericblass · 2016-10-17, 12:20

Thanks @philbell for the such a describe information, i hope it will be very much helpful for me. Cool

philbell · (This post was last modified: 2016-10-18, 02:03 by philbell.)

The kodi log is here: http://pastebin.com/pEDVYJMH

philbell · 2016-10-18, 02:03

The full debug log is here:
https://paste.ubuntu.com/23341389

philbell · 2016-11-05, 04:38

To tkgafs: My problem has exactly the effect that you mention, except it is 100% reproducible, on Android and on Windows, and there were errors in the log:

18:31:13 T:6800 ERROR: ffmpeg[1A90]: [mp3] Header missing
18:31:13 T:6800 ERROR: Previous line repeats 1 times.
18:31:13 T:6800 ERROR: CAudioDecoder: Error while decoding 1

And on both OS'es, other music players play the whole file without (visible) problems.