Odroid N2 Music playback hang

dswebb · 3 June 2019 16:09

Hi,

Bit of a weird issue with audio playback in kodi on the n2. Everytime I select a file to play or it switches between songs there is ~30 second pause and the entire kodi interface freezes. Doesn’t matter which type of audio file it is. Have no issues with video playback, just audio.

am running: nightly_20190531

debug log:

2019-06-03 16:05:32.269 T:3763200880   DEBUG: Previous line repeats 5 times.
2019-06-03 16:05:32.269 T:3763200880   DEBUG: EventClient: button code 0 pressed
2019-06-03 16:05:32.270 T:4059648016   DEBUG: HandleKey: 11 (0x0b, obc244) pressed, action is Select
2019-06-03 16:05:32.271 T:4059648016   DEBUG: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 93  ORDER BY albumartistview.iOrder
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnClear from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnClear
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.273 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.273 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers(/storage/xbmc/MP3/Chinese Man/Shikantaza (Remix)/06-chinese_man-golden_age_(mophono_remix)-5d33e88f.mp3)
2019-06-03 16:05:32.274 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
2019-06-03 16:05:32.274 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
2019-06-03 16:05:32.274 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
2019-06-03 16:05:32.275 T:4059648016   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
2019-06-03 16:05:32.275 T:4059648016   DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
2019-06-03 16:05:32.275 T:3672974192   DEBUG: Thread PAPlayer start, auto delete: false
2019-06-03 16:05:32.275 T:3672974192   DEBUG: PAPlayer::Process - Playback started
2019-06-03 16:05:32.275 T:3546317680   DEBUG: CreateInputStream: All else failed, creating CDVDInputStreamFile
2019-06-03 16:05:32.275 T:4059648016   DEBUG: FreeVisualisation() done
2019-06-03 16:05:32.275 T:4059648016   DEBUG: ADDON: Dll Initializing - Shadertoy
2019-06-03 16:05:32.275 T:4059648016   DEBUG: SECTION:LoadDLL(/storage/.kodi/addons/visualization.shadertoy/visualization.shadertoy.so.1.1.10)
2019-06-03 16:05:32.275 T:4059648016   DEBUG: Loading: /storage/.kodi/addons/visualization.shadertoy/visualization.shadertoy.so.1.1.10
2019-06-03 16:05:32.277 T:4059648016   DEBUG: CAddonSettings[visualization.shadertoy]: loading setting definitions
2019-06-03 16:05:32.277 T:4059648016   DEBUG: CAddonSettings[visualization.shadertoy]: trying to load setting definitions from old format...
2019-06-03 16:05:32.308 T:3546317680   DEBUG: DVDFactoryDemuxer: Create CDVDDemuxFFmpeg.
2019-06-03 16:05:32.584 T:4059648016   DEBUG: CMusicGUIInfo::InitCurrentItem(musicdb://recentlyplayedalbums/93/6072.mp3?albumid=93)
2019-06-03 16:05:32.587 T:4059648016   DEBUG: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 93  ORDER BY albumartistview.iOrder
2019-06-03 16:05:32.589 T:4059648016   DEBUG: Loading additional tag info for file /storage/xbmc/MP3/Chinese Man/Shikantaza (Remix)/06-chinese_man-golden_age_(mophono_remix)-5d33e88f.mp3
2019-06-03 16:05:32.901 T:3546317680   DEBUG: Open - probing detected format [mp3]
2019-06-03 16:05:32.901 T:3546317680   DEBUG: Open - setting usetoc to 0 for accurate VBR MP3 seek
2019-06-03 16:05:32.901 T:3546317680   DEBUG: Open - avformat_find_stream_info starting
2019-06-03 16:05:32.903 T:3546317680   DEBUG: Open - av_find_stream_info finished
2019-06-03 16:05:32.903 T:3546317680   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
2019-06-03 16:05:32.903 T:3546317680   DEBUG: DVDDemuxFFmpeg::AddStream - fps:0/0 tbr:90000/1 tbn:90000/1
2019-06-03 16:05:32.903 T:3546317680   DEBUG: DVDDemuxFFmpeg::AddStream - fps:90000/1p
2019-06-03 16:05:32.903 T:3546317680   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
2019-06-03 16:05:32.903 T:3546317680  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder mp3float
2019-06-03 16:05:33.953 T:3917476720   DEBUG: CWebServer[8080]: request received for /jsonrpc





2019-06-03 16:06:02.277 T:3526112112   DEBUG: Previous line repeats 14 times.
2019-06-03 16:06:02.277 T:3526112112   DEBUG: Thread JobWorker 3526112112 terminating (autodelete)
2019-06-03 16:06:02.277 T:3590570864   DEBUG: Thread JobWorker 3590570864 terminating (autodelete)
2019-06-03 16:06:02.278 T:3534504816   DEBUG: Thread JobWorker 3534504816 terminating (autodelete)
2019-06-03 16:06:03.126 T:4059648016   DEBUG: CPlayerGUIInfo::InitCurrentItem(musicdb://recentlyplayedalbums/93/6072.mp3?albumid=93)
2019-06-03 16:06:03.127 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
2019-06-03 16:06:03.127 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
2019-06-03 16:06:03.127 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
2019-06-03 16:06:03.127 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
2019-06-03 16:06:03.128 T:3546317680   DEBUG: ffmpeg[D3608370]: [mp3float] Could not update timestamps for skipped samples.
2019-06-03 16:06:03.128 T:3546317680   DEBUG: SeekTime - seek ended up on time 0.000
2019-06-03 16:06:03.130 T:3546317680   DEBUG: Previous line repeats 1 times.
2019-06-03 16:06:03.130 T:3546317680   DEBUG: ffmpeg[D3608370]: [mp3float] Could not update timestamps for skipped samples.
2019-06-03 16:06:03.224 T:3546317680    INFO: AudioDecoder: File is queued
2019-06-03 16:06:03.227 T:4039418736   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-06-03 16:06:03.227 T:4059648016   DEBUG: ------ Window Init (DialogBusy.xml) ------
2019-06-03 16:06:03.228 T:3546317680   DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000
2019-06-03 16:06:03.246 T:3546317680    INFO: PAPlayer::PrepareStream - Ready
2019-06-03 16:06:03.251 T:3672974192   DEBUG: OnAVStarted: CApplication::OnAVStarted
2019-06-03 16:06:03.252 T:4039418736   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
2019-06-03 16:06:03.253 T:4059644784   DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
2019-06-03 16:06:03.253 T:4059644784   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
2019-06-03 16:06:03.286 T:4059648016   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
2019-06-03 16:06:03.286 T:4059648016   DEBUG: Updating visualization albumart: image://music@%2fstorage%2fxbmc%2fMP3%2fChinese%20Man%2fShikantaza%20(Remix)%2f01-chinese_man_feat_kendra_morris_dillon_cooper_mophono_remix-liar_(mophono_remix)-d2035d8b.mp3/
2019-06-03 16:06:04.794 T:3554710384   DEBUG: CWebServer[8080]: request received for /jsonrpc

count.zero · 30 October 2019 14:26

I’ve got the same issue … do you have a solution for that?
I connect to my music via NFS; maybe that’s the problem?

frodo19 · 30 October 2019 14:34

Same here, my files on NAS, used nfs server, to connect.

count.zero · 1 November 2019 21:42

So, I’m not the only one, but there seems to be no solution out there.
I will try a purge of my music-DB -> reconnect via SMB and rebuild these days … will post my experience

dswebb · 10 November 2019 18:22

unfortunately I’ve never found a fix for this. didn’t have an issue when I was using an atom x86 kodi box mounting the same SMB share. so not sure if it is something that can be configured away or what. it isn’t seemingly a load issue on the kodi machine as CPU almost idles during any next song / playlist start operation.