A few songs won't play

anon29391075 · 9 February 2019 16:44

Hi all, I’ve encountered this problem on my 2 S905X boxes since I switched them from kszaq’s LE/Kodi 17 to CE/Kodi 18 a few months ago: some songs from a small number of albums on my library (located on a samba server) won’t play.

They played OK on LE and still do on my laptop’s Rhythmbox media player. Some songs of these albums play OK while others don’t, all have the same format, tags etc. For example of one album 7 songs will play and 4 won’t.

I only report it now because the final release is approaching and the thing wasn’t bothering me much, I thought it could have just been an alpha version bug.

Below are some audio debug logs for a song that works and the next on the same album that doesn’t, this is on CE 8.99.2. Looking at it it would seem a file issue but that can’t be as it works on other devices.

Song that works:

15:41:54.796 T:4089331728   DEBUG: HandleKey: sleep (0xdf) pressed, action is SkipNext
15:41:54.797 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers(smb://10.1.1.1/media/Music/Artist/Album/03 Track that works.m4a)
15:41:54.797 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
15:41:54.798 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
15:41:54.799 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
15:41:54.799 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
15:41:54.799 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
15:41:54.799 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
15:41:54.805 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnSpeedChanged from xbmc
15:41:54.805 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSpeedChanged
15:41:54.877 T:3976381296   DEBUG: Thread FileCache 3976381296 terminating
15:41:54.878 T:3800466288   DEBUG: CSMBFile::Close closing fd 10000
15:41:54.880 T:3967988592   DEBUG: DoWork - Saving file state for audio item musicdb://artists/106/170/2093.m4a?albumartistsonly=false&albumid=170&artistid=106
15:41:54.882 T:4066337648   DEBUG: CActiveAE::DiscardStream - audio stream deleted
15:41:54.882 T:4066337648   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:41:54.890 T:3800466288   DEBUG: Previous line repeats 2 times.
15:41:54.890 T:3800466288   DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped
15:41:54.890 T:3800466288   DEBUG: Thread PAPlayer 3800466288 terminating
15:41:54.890 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
15:41:54.890 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
15:41:54.890 T:4089331728   DEBUG: FreeVisualisation() started
15:41:54.890 T:4089331728   DEBUG: FreeVisualisation() done
15:41:54.896 T:4089331728   DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
15:41:54.896 T:3800466288   DEBUG: Thread PAPlayer start, auto delete: false
15:41:54.896 T:3800466288   DEBUG: PAPlayer::Process - Playback started
15:41:54.896 T:4089331728   DEBUG: CMusicGUIInfo::InitCurrentItem(musicdb://artists/106/170/2094.m4a?albumartistsonly=false&albumid=170&artistid=106)
15:41:54.897 T:3967988592   DEBUG: CreateInputStream: All else failed, creating CDVDInputStreamFile
15:41:54.898 T:3967988592   DEBUG: CFileCache::Open - opening <media/Music/Artist/Album/03 Track that works.m4a> using cache
15:41:54.946 T:3967988592   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Artist/Album/03 Track that works.m4a, fd=10000
15:41:54.956 T:4089331728   DEBUG: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 170  ORDER BY albumartistview.iOrder
15:41:54.958 T:3976381296   DEBUG: Thread FileCache start, auto delete: false
15:41:54.958 T:3967988592   DEBUG: DVDFactoryDemuxer: Create CDVDDemuxFFmpeg.
15:41:54.970 T:4089331728   DEBUG: Loading additional tag info for file smb://10.1.1.1/media/Music/Artist/Album/03 Track that works.m4a
15:41:54.976 T:3967988592   DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
15:41:54.978 T:4089331728   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Artist/Album/03 Track that works.m4a, fd=10001
15:41:55.000 T:3967988592   DEBUG: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, timescale not set
15:41:55.002 T:3967988592   DEBUG: Open - avformat_find_stream_info starting
15:41:55.017 T:3967988592   DEBUG: Open - av_find_stream_info finished
15:41:55.018 T:3967988592   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
15:41:55.018 T:3967988592   DEBUG: DVDDemuxFFmpeg::AddStream - fps:0/0 tbr:90000/1 tbn:90000/1
15:41:55.018 T:3967988592   DEBUG: DVDDemuxFFmpeg::AddStream - fps:90000/1 i/p:0
15:41:55.018 T:3967988592   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
15:41:55.020 T:3967988592  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
15:41:55.047 T:3967988592   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Artist/Album/03 Track that works.m4a, fd=10002
15:41:55.303 T:4089331728   DEBUG: CSMBFile::Close closing fd 10001
15:41:55.318 T:4089331728   DEBUG: CPlayerGUIInfo::InitCurrentItem(musicdb://artists/106/170/2094.m4a?albumartistsonly=false&albumid=170&artistid=106)
15:41:55.318 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
15:41:55.319 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
15:41:55.319 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
15:41:55.319 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
15:41:55.397 T:3967988592   DEBUG: CSMBFile::Close closing fd 10002
15:41:55.412 T:3976381296    INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
15:41:55.414 T:3967988592   DEBUG: SeekTime - unknown position after seek
15:41:55.419 T:4089331728   DEBUG: Previous line repeats 1 times.
15:41:55.419 T:4089331728   DEBUG: ------ Window Init (DialogBusy.xml) ------
15:41:55.421 T:4089331728   ERROR: CBinaryAddonManager::GetInstalledAddonInfo: Requested addon '' unknown as binary
15:41:55.469 T:4066337648   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:41:55.540 T:3967988592    INFO: AudioDecoder: File is queued
15:41:55.541 T:3967988592   DEBUG: AudioDecoder::GetReplayGain - Final Replaygain applied: 1.000000, Track/Album Gain 89.000000, Peak 1.000000
15:41:55.543 T:4066337648   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:41:55.551 T:3967988592    INFO: PAPlayer::PrepareStream - Ready
15:41:55.553 T:3800466288   DEBUG: OnAVStarted: CApplication::OnAVStarted
15:41:55.554 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
15:41:55.554 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
15:41:55.576 T:4089331728   DEBUG: ------ Window Deinit (DialogBusy.xml) ------

Song that doesn’t work:

15:41:56.857 T:4011844464   DEBUG: LIRC: - NEW 197 0 KEY_NEXT devinput (KEY_NEXT)
15:41:56.861 T:4089331728   DEBUG: HandleKey: sleep (0xdf) pressed, action is SkipNext
15:41:56.862 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers(smb://10.1.1.1/media/Music/Artist/Album/04 Track that doesn't work.m4a)
15:41:56.862 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
15:41:56.862 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
15:41:56.862 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
15:41:56.862 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
15:41:56.863 T:4089331728   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
15:41:56.863 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
15:41:56.863 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
15:41:56.864 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
15:41:56.864 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
15:41:56.864 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
15:41:56.864 T:4089331728   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
15:41:56.867 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnSpeedChanged from xbmc
15:41:56.867 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnSpeedChanged
15:41:56.929 T:3976381296   DEBUG: Thread FileCache 3976381296 terminating
15:41:56.932 T:3800466288   DEBUG: CSMBFile::Close closing fd 10000
15:41:56.945 T:3967988592   DEBUG: DoWork - Saving file state for audio item musicdb://artists/106/170/2094.m4a?albumartistsonly=false&albumid=170&artistid=106
15:41:56.948 T:4066337648   DEBUG: CActiveAE::DiscardStream - audio stream deleted
15:41:56.948 T:4066337648   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
15:41:56.953 T:3800466288   DEBUG: Previous line repeats 2 times.
15:41:56.954 T:3800466288   DEBUG: OnPlayBackStopped: CApplication::OnPlayBackStopped
15:41:56.954 T:3800466288   DEBUG: Thread PAPlayer 3800466288 terminating
15:41:56.954 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
15:41:56.954 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
15:41:56.954 T:4089331728   DEBUG: FreeVisualisation() started
15:41:56.954 T:4089331728   DEBUG: FreeVisualisation() done
15:41:56.965 T:4089331728   DEBUG: OnPlayBackStarted: CApplication::OnPlayBackStarted
15:41:56.965 T:3800466288   DEBUG: Thread PAPlayer start, auto delete: false
15:41:56.966 T:3800466288   DEBUG: PAPlayer::Process - Playback started
15:41:56.966 T:3967988592   DEBUG: CreateInputStream: All else failed, creating CDVDInputStreamFile
15:41:56.966 T:4089331728   DEBUG: CMusicGUIInfo::InitCurrentItem(musicdb://artists/106/170/2095.m4a?albumartistsonly=false&albumid=170&artistid=106)
15:41:56.967 T:3967988592   DEBUG: CFileCache::Open - opening <media/Music/Music/Artist/Album/04 Track that doesn't work.m4a> using cache
15:41:56.991 T:4089331728   DEBUG: SELECT albumview.*,albumartistview.*  FROM albumview  JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum  WHERE albumview.idAlbum = 170  ORDER BY albumartistview.iOrder
15:41:56.997 T:4089331728   DEBUG: Loading additional tag info for file smb://10.1.1.1/media/Music/Music/Artist/Album/04 Track that doesn't work.m4a
15:41:57.004 T:3967988592   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Music/Artist/Album/04 Track that doesn't work.m4a, fd=10000
15:41:57.012 T:3976381296   DEBUG: Thread FileCache start, auto delete: false
15:41:57.012 T:3967988592   DEBUG: DVDFactoryDemuxer: Create CDVDDemuxFFmpeg.
15:41:57.014 T:4089331728   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Music/Artist/Album/04 Track that doesn't work.m4a, fd=10002
15:41:57.029 T:3967988592   DEBUG: Open - probing detected format [mov,mp4,m4a,3gp,3g2,mj2]
15:41:57.076 T:3967988592   DEBUG: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, timescale not set
15:41:57.079 T:3967988592   DEBUG: Open - avformat_find_stream_info starting
15:41:57.084 T:3976381296    INFO: CFileCache::Process - Source read didn't return any data! Hit eof(?)
15:41:57.085 T:4089331728   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.086 T:4089331728 WARNING: underflow: Error reading file - assuming eof
15:41:57.086 T:4089331728   ERROR: Seek - Error( -1, 22, Invalid argument )
15:41:57.086 T:4089331728   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.086 T:4089331728 WARNING: underflow: Error reading file - assuming eof
15:41:57.087 T:4089331728   ERROR: Seek - Error( -1, 22, Invalid argument )
15:41:57.087 T:4089331728   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.087 T:4089331728 WARNING: underflow: Error reading file - assuming eof
15:41:57.087 T:4089331728   ERROR: Seek - Error( -1, 22, Invalid argument )
15:41:57.087 T:4089331728   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.087 T:4089331728 WARNING: underflow: Error reading file - assuming eof
15:41:57.088 T:4089331728   ERROR: Seek - Error( -1, 22, Invalid argument )
15:41:57.088 T:4089331728   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.088 T:4089331728 WARNING: underflow: Error reading file - assuming eof
15:41:57.088 T:4089331728   ERROR: Seek - Error( -1, 22, Invalid argument )
15:41:57.088 T:4089331728   DEBUG: CSMBFile::Close closing fd 10002
15:41:57.089 T:4089331728   DEBUG: CPlayerGUIInfo::InitCurrentItem(musicdb://artists/106/170/2095.m4a?albumartistsonly=false&albumid=170&artistid=106)
15:41:57.089 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnChanged from xbmc
15:41:57.089 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1024, from xbmc, message OnChanged
15:41:57.089 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnPlay from xbmc
15:41:57.090 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnPlay
15:41:57.106 T:3976381296   ERROR: Read - Error( -1, 103, Software caused connection abort )
15:41:57.106 T:3976381296   DEBUG: CFileCache::Process - Source read returned a fatal error! Will wait for buffer to empty.
15:41:57.106 T:3976381296   DEBUG: Thread FileCache 3976381296 terminating
15:41:57.157 T:3967988592   DEBUG: Open - av_find_stream_info finished
15:41:57.158 T:3967988592   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 0
15:41:57.158 T:3967988592   DEBUG: DVDDemuxFFmpeg::AddStream - fps:0/0 tbr:90000/1 tbn:90000/1
15:41:57.158 T:3967988592   DEBUG: DVDDemuxFFmpeg::AddStream - fps:90000/1 i/p:0
15:41:57.158 T:3967988592   DEBUG: CDVDDemuxFFmpeg::AddStream ID: 1
15:41:57.161 T:3967988592  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
15:41:57.189 T:4089331728   DEBUG: ------ Window Init (DialogBusy.xml) ------
15:41:57.191 T:4089331728   ERROR: CBinaryAddonManager::GetInstalledAddonInfo: Requested addon '' unknown as binary
15:41:57.216 T:3967988592   DEBUG: CSMBFile::Open - opened smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Music/Artist/Album/04 Track that doesn't work.m4a, fd=10002
15:41:57.283 T:3967988592   DEBUG: CSMBFile::Close closing fd 10002
15:41:57.286 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0xba: partial file
15:41:57.286 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0x241: partial file
15:41:57.286 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0x41e: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0x5ec: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0x7a8: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0x969: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0xb29: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0xcb9: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0xe55: partial file
15:41:57.287 T:3967988592   ERROR: ffmpeg[EC82B370]: [mov,mp4,m4a,3gp,3g2,mj2] stream 0, offset 0xfe0: partial file
15:41:57.287 T:3967988592   DEBUG: SeekTime - seek ended up on time 209140375552790.594
15:41:57.290 T:3967988592   DEBUG: Previous line repeats 1 times.
15:41:57.290 T:3967988592    INFO: PAPlayer::QueueNextFileEx - Error reading samples
15:41:57.291 T:3967988592   DEBUG: CSMBFile::Close closing fd 10000
15:41:57.292 T:3967988592   DEBUG: OnAVStarted: CApplication::OnAVStarted
15:41:57.292 T:4089328496   DEBUG: CAnnouncementManager - Announcement: OnAVStart from xbmc
15:41:57.293 T:4089328496   DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnAVStart
15:41:57.293 T:3967988592   DEBUG: OnQueueNextItem: CApplication::OnQueueNextItem

JimmyS · 9 February 2019 17:27

did you also checked journalctl -xe / dmesg for logs?

Although I have slightly different symptoms, one of my “open issues” is samba strangeness with 64 / 32 locks, which l experience with data (video, audio) disk (ext4) from previous 32bit LE installation. And I found my errors in journal, and also you can find samba logs there. The strict locking = 0 is workaround for my issue, but you may have other problem, but just in case…

To exclude network share you could try copy one of non-working song into /storage, to eliminate possible network issue…

Also try rename 04 Track that doesn't work.m4a to the test.mp4, to eliminate charset issues. Could it be also related to specific permissions, for some files maybe.

anon29391075 · 9 February 2019 18:27

Thanks for your reply Jimmy. I’ve checked journalctl -f while playing the songs and no messages are showing other than remote keys events. I’ve also copied the 2 songs to /storage and both play fine so it does look like a samba problem (btw the actual file names don’t have special characters, I only changed the names on the logs to highlight which one is which). Did you configure strict locking = 0 on samba.conf?

JimmyS · 9 February 2019 18:32

Hmm,
then must be something with samba configuration / network share permissions on specific disc. I cant tell further advice without some log evidence, hopefully someone else will have some more tips to do (maybe try enable debug in Kodi for SMB?).

Regards strict locking, carefull with that, you can try it (.config/samba.conf, in case you are using HDD connected to CE box), but I think you have different problems (the locks in my case are problematic for files bigger than 2GB, no seek option on those and you cant play more than 2GBs).

One more from top of my head (but I doubt its your case) unusually looo…/…/…/…/oooong file path. Not sure if its problem in todays world, but I remember time when was But my best bet is some permission issue / smb…

anon29391075 · 9 February 2019 20:11

OK, then I won’t try strict locking, the files are small. The permissions are the same for both files.

I’ve enabled SMB debug on Kodi, kodi.log is as earlier but now there’s a lot of output on journalctl, below is the small part that shows the error, which comes up only for the file that doesn’t work: smbc_error 1 233 (0xe9) -> 103.

Some more info about the samba server: it’s an Asus router running Asuswrt Merlin 384.9. I’m 100% sure these few song files did work with LE/Kodi 17 as I have 2 boxes and I switched them to CE/Kodi 18 one at a time so for a few weeks I had one on LE and the other on CE and only the CE one had this issue. I also remember that on LE I used smbv2 as the minimum version on Kodi SMB prefs and it worked fine while if I do that on CE SMB stops working. The server can do both v1 and v2. Perhaps it’s an SMB bug on Kodi 18?

Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_stat(smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Artist/Album/04%20Track%20that%20doesn't%20work.m4a)
Feb 09 20:30:37 l1 kodi.sh[2971]: SMBC_getatr: sending qpathinfo
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0xd8037c00, 131072)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 131072
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0xd8037c00, 131072)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 131072
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 65536
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0xd8037c00, 131072)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 70944
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 65536
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0xd8037c00, 131072)
Feb 09 20:30:37 l1 kodi.sh[2971]:   --> 0
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_read(0x178cd98, 65536)
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103
Feb 09 20:30:37 l1 kodi.sh[2971]: cli_close failed on smb://USERNAME:PASSWORD@10.1.1.1/media/Music/Artist/Album/04%20Track%20that%20doesn't%20work.m4a. purging server.
Feb 09 20:30:37 l1 kodi.sh[2971]: map_errno_from_nt_status: 32 bit codes: code=c000020c
Feb 09 20:30:37 l1 kodi.sh[2971]: smbc_error 1 233 (0xe9) -> 103

JimmyS · 9 February 2019 21:45

hmm, ok, then you seems to struggle with samba client…

-had one on LE and the other on CE and only the CE one had this issue
-I also remember that on LE I used smbv2 as the minimum version on Kodi SMB prefs and it worked fine while if I do that on CE SMB stops working.

assuming you not comparing Kodi CE18 - LE18, but CE18 - LE17, right? If not, its something which CE devs could look and check.
Personally, I switched from LE16.1 → CE18, didnt change smb version/etc defaults, and it works for me. With exception described above (existing USB HDD with data), connected to Rpi2 box before in one of my installations (my primary configuration has HDD connected through network).

Googling

map_errno_from_nt_status: 32 bit codes: code=c000020c

returns some fixed bugs in SMB (dont have time to look it further now), maybe its also case of smb client in jeOS (?)…

anon29391075 · 9 February 2019 22:05

Yep, CE18 - LE17. A lot of hits on Google for each of these errors, I think the bottom line is, given it worked on LE17 and the server side stayed the same, it’s probably a Kodi 18 bug, after all it’s a fresh new major release. It’s probably going to disappear in one of the point releases, hopefully soon as I’m starting to miss some of these songs

JimmyS · 9 February 2019 22:17

strange thing is, that some of files works and some not - through SMB… I didnt experience that - ie. all SMB is somehow affected, or not…

anon29391075 · 9 February 2019 22:44

Indeed, the symptoms would point more to corrupt files or broken disk sectors but thankfully the other devices disprove that, the only common point is Kodi 18.