[User 224999]

Hi guys,

Did something change recently in the logic for playing audio streams or mimetype detection ?

The spotify addon I created uses the libspotify implementation to provide a stream of audio packets to the Kodi player.

URL for the listitems is set as http pointing to the http proxy started by libspotify which streams the spotify music.

Working perfectly fine in Kodi 15, 16 and older builds of Krypton but somehow now it's failing.
Some users are reporting it's working when selecting VideoPlayer as the player.

Even if I set the mimetype to "audio/x-wav" this won't stop Kodi from probing the http address which btw sets the headers correctly.

For now I fixed a small workaround by setting the streaminfo on teh listitems which forces playback with VideoPlayer but it's pretty slow.

The problem is that if you select a track in the addon, Kodi completely freezes for almost a minute and when it responds again there is the "playback failed" message. I've turned on debug logging in the libspotify httpproxy and I can see the request but after that it's timed out.


Small snippet of the log with the errors:

PHP Code:

01:14:02 T:1828   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
01:14:12 T:14300   ERROR: CCurlFile::Stat - Failed: Timeout was reached(28) for http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:14:12 T:14300   DEBUG: CCurlFile::GetMimeType - http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29 -> failed
01:14:12 T:14300   DEBUG: CFileCache::Open - opening <track/386RUes7n1uM1yfzgeUuwp.wav> using cache
01:14:12 T:14300   DEBUG: CurlFile::Open(16BA7F78) http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0
01:14:31 T:12984   DEBUG: Thread JobWorker 12984 terminating (autodelete)
01:14:31 T:6380   DEBUG: Thread JobWorker 6380 terminating (autodelete)
01:14:31 T:7000   DEBUG: Thread JobWorker 7000 terminating (autodelete)
01:14:31 T:12956   DEBUG: Thread JobWorker 12956 terminating (autodelete)
01:14:32 T:14300   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
01:14:32 T:14300   ERROR: CCurlFile::Open failed with code 0 for http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:14:32 T:14300   ERROR: XFILE::CFileCache::Open - failed to open source <http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29>
01:14:32 T:14300   ERROR: VideoPlayerCodec::Init: Error opening file http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:14:42 T:14300   ERROR: CCurlFile::Stat - Failed: Timeout was reached(28) for http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:14:42 T:14300   DEBUG: CCurlFile::GetMimeType - http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29 -> failed
01:14:42 T:14300   DEBUG: CFileCache::Open - opening <track/386RUes7n1uM1yfzgeUuwp.wav> using cache
01:14:42 T:14300   DEBUG: CurlFile::Open(03CFFF18) http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0
01:15:02 T:14300   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
01:15:02 T:14300   ERROR: CCurlFile::Open failed with code 0 for http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:15:02 T:14300   ERROR: XFILE::CFileCache::Open - failed to open source <http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29>
01:15:02 T:14300   ERROR: VideoPlayerCodec::Init: Error opening file http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:15:02 T:14300   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29
01:15:02 T:14300 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
01:15:02 T:14300   DEBUG: CApplication::OnQueueNextItem: play state was 1, starting 1
01:15:02 T:14300   ERROR: Playlist Player: skipping unplayable item: 0, path [http://127.0.0.1:8090/track/386RUes7n1uM1yfzgeUuwp.wav?idx=0|X-Spotify-Token=b5951c9547f17e6f20e78f424b0c86ace16f0eef&User-Agent=Spotify%2F1.0.17+%28XBMC%2F17.0-ALPHA2+Git%3A20160602-91a1cd5%29]
01:15:02 T:14300   DEBUG: Playlist Player: one or more items failed to play... aborting playback 


As it looks in the log Kodi is probing the url for the MimeType even if I have that specified in my listitem:

li.setProperty('do_not_analyze', 'true')
li.setMimeType("audio/x-wav")

Besides that, I checked and the headers are correctly set by the libspotify webproxy.


Not sure if this is a bug ?

[User 224999]

Any news about this ? Something apparently changed in Krypton because it's working fine with Jarvis.

[FernetMenta]

There is a listitem property you can set. That will tell Kodi not to stat the url.
https://github.com/xbmc/xbmc/commit/2da4...318ee6a3f0

[User 224999]

Thanks! That was exactly what I was looking for, it's working like a charm now.

[User 224999]

Hmm, I've spoken too soon i guess... The issue IS fixed on a Windows build of Krypton but it isn't on a Linux (Millhouse) build...

http://forum.kodi.tv/showthread.php?tid=...pid2374062

[User 224999]

There is a listitem property you can set. That will tell Kodi not to stat the url.
https://github.com/xbmc/xbmc/commit/2da4...318ee6a3f0

This fix worked for a little while but now it's definitely broken again.
My Spotify add-on refuses to play on Kodi Krypton.

Tested on my Windows machine, latest nightly but also have reports from users on other platforms that it isn't working.

Looks like Kodi isn't able to determine the audio stream.

This is the relevant part in the log after I started to play a song:

PHP Code:

10:34:35 T:4068   DEBUG: XFILE::CPluginDirectory::StartScript - calling plugin Spotify('plugin://plugin.audio.spotify/','17','?action=play_track&trackid=1xznGGDReH1oQq0xzbwXa3')
10:34:35 T:11488   DEBUG: Thread LanguageInvoker start, auto delete: false
10:34:35 T:11488    INFO: initializing python engine.
10:34:35 T:11488   DEBUG: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): start processing
10:34:35 T:7888   DEBUG: CAnnouncementManager - Announcement: OnAdd from xbmc
10:34:35 T:7888   DEBUG: GOT ANNOUNCEMENT, type: 2, from xbmc, message OnAdd
10:34:35 T:5308   DEBUG: Thread scriptobs start, auto delete: false
10:34:35 T:11488   DEBUG: -->Python Interpreter Initialized<--
10:34:35 T:11488   DEBUG: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): the source file to load is "C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py"
10:34:35 T:11488   DEBUG: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): setting the Python path to C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify;C:\Kodi\Kodi_Krypton\portable_data\addons\script.module.requests\lib;C:\Kodi\Kodi_Krypton\portable_data\addons\script.module.simplejson\lib;C:\Kodi\Kodi_Krypton\system\python\DLLs;C:\Kodi\Kodi_Krypton\system\python\Lib;C:\Kodi\Kodi_Krypton\python27.zip;C:\Kodi\Kodi_Krypton\system\python\lib\plat-win;C:\Kodi\Kodi_Krypton\system\python\lib\lib-tk;C:\Kodi\Kodi_Krypton;C:\Kodi\Kodi_Krypton\system\python;C:\Kodi\Kodi_Krypton\system\python\lib\site-packages
10:34:35 T:11488   DEBUG: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): entering source directory C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify
10:34:35 T:11488   DEBUG: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): instantiating addon using automatically obtained id of "plugin.audio.spotify" dependent on version 2.1.0 of the xbmc.python api
10:34:35 T:4068   DEBUG: ------ Window Init (DialogBusy.xml) ------
10:34:35 T:4068   DEBUG: ------ Window Deinit (DialogNotification.xml) ------
10:34:36 T:11488    INFO: CPythonInvoker(23, C:\Kodi\Kodi_Krypton\portable_data\addons\plugin.audio.spotify\plugin.py): script successfully run
10:34:36 T:11488    INFO: Python script stopped
10:34:36 T:11488   DEBUG: Thread LanguageInvoker 11488 terminating
10:34:36 T:5308   DEBUG: Thread scriptobs 5308 terminating
10:34:36 T:4068    INFO: XCURL::DllLibCurlGlobal::easy_aquire - Created session to http://127.0.0.1
10:34:46 T:4068   ERROR: CCurlFile::Stat - Failed: Timeout was reached(28) for http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:34:46 T:4068   DEBUG: CCurlFile::GetMimeType - http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29 -> failed
10:34:46 T:4068   DEBUG: Loading settings for http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers(http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29)
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mms/udp
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: streams
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: aacp/sdp
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: mp2
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
10:34:46 T:4068   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: adding audiodefaultplayer (PAPlayer)
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: for video=0, audio=1
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: VideoPlayer
10:34:46 T:4068   DEBUG: CPlayerCoreFactory::GetPlayers: added 2 players
10:34:46 T:4068   DEBUG: CFileCache::Open - opening <track/1xznGGDReH1oQq0xzbwXa3.wav> using cache
10:34:46 T:4068   DEBUG: CurlFile::Open(0F10A0B8) http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1
10:35:03 T:12880   DEBUG: EPG - EPG::CEpg::UpdateFromScraper - updating EPG for channel 'Fox Life' from client '1924689120'
10:35:03 T:12880   DEBUG: EPG - EPG::CEpg::UpdateFromScraper - updating EPG for channel 'MelodieTV' from client '1924689120'
10:35:03 T:4748   DEBUG: CPVRTimers - PVR::CPVRTimers::Update - updating timers
10:35:03 T:4748   DEBUG: PVRTimers - PVR::CPVRTimers::UpdateEntries - updated timer 1 on client 1924689120
10:35:05 T:7976   DEBUG: Thread JobWorker 7976 terminating (autodelete)
10:35:05 T:2384   DEBUG: Thread JobWorker 2384 terminating (autodelete)
10:35:05 T:12964   DEBUG: Thread JobWorker 12964 terminating (autodelete)
10:35:06 T:4068   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
10:35:06 T:4068   ERROR: CCurlFile::Open failed with code 0 for http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:35:06 T:4068   ERROR: XFILE::CFileCache::Open - failed to open source <http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29>
10:35:06 T:4068   ERROR: VideoPlayerCodec::Init: Error opening file http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:35:06 T:4068   DEBUG: CFileCache::Open - opening <track/1xznGGDReH1oQq0xzbwXa3.wav> using cache
10:35:06 T:4068   DEBUG: CurlFile::Open(0F10AE68) http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1
10:35:26 T:4068   ERROR: CCurlFile::FillBuffer - Failed: Timeout was reached(28)
10:35:26 T:4068   ERROR: CCurlFile::Open failed with code 0 for http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:35:26 T:4068   ERROR: XFILE::CFileCache::Open - failed to open source <http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29>
10:35:26 T:4068   ERROR: VideoPlayerCodec::Init: Error opening file http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:35:26 T:4068   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:8090/track/1xznGGDReH1oQq0xzbwXa3.wav?idx=1|X-Spotify-Token=9b052f88eb166fc05766cf3299ac0df84d4ebd38&User-Agent=Spotify%2F1.0.19+%28XBMC%2F17.0-BETA1+Git%3A20160818-c2b48b0%29
10:35:26 T:4068 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
10:35:26 T:4068   DEBUG: CApplication::OnQueueNextItem: play state was 1, starting 1
10:35:26 T:4068   DEBUG: CApplication::OnPlayBackStopped: play state was 1, starting 0
10:35:26 T:4068   ERROR: Playlist Player: skipping unplayable item: 0, path [plugin://plugin.audio.spotify/?action=play_track&trackid=1xznGGDReH1oQq0xzbwXa3]
10:35:26 T:4068   DEBUG: Playlist Player: one or more items failed to play... aborting playback 


If I open the url in the browser or VLC the playback works fine.

The proxy that needs to be used for spotify playback accepts a 200 request to get the whole file bytes or it accepts ranges.
I've been debugging the proxy and Kodi does succesfully do a HEAD request, followed by a GET.

The following HTTP headers are returned by the proxy:

cherrypy.response.headers['Content-Type'] = 'audio/x-wav'
cherrypy.response.headers['Content-Length'] = filesize
cherrypy.response.headers['Accept-Ranges'] = 'bytes'


I'm out of options here. The only thing I can think of is that the recent change in the Kodi player broke the playback of this audio stream.

[User 224999]

Created a Trac issue for this too:
http://trac.kodi.tv/ticket/16853

[FernetMenta]

problem reports without full debug logs don't get much attention in general

[User 224999]

problem reports without full debug logs don't get much attention in general

Sorry, I've added a full debug log to the ticket now.

http://trac.kodi.tv/attachment/ticket/16853/kodi.log

[jpoppe]

@marcelveldt: I have done some investigation (a day of work :p) (for my own (and for now private and unfinished) Spotify plugin).

It works until commit: 6ad23bd5bd325c60292d08d0f44d32a62609c89f

It starts breaking with commits:
82218b570c8d5e7d2ace70cbe71f24e101e72163
75734619ff1cc9f2065f5b3df049a1d40941e8e6
95ffdcc381288c9442355be486721f83e13b7007

Hope it helps

[Bad_CRC]

Hi,

I tried your plugin because Spotlight wasn't working (same error trying to stream from the libspotify proxy) and is still broken with the RC3.

I can post the full log but is the same as yours.

Code:

16:14:31.414 T:2954704960   DEBUG: CFileCache::Open - opening <track/28RFYsTEk5mk6BMdMxfHCr.wav> using cache
16:14:31.414 T:2954704960   DEBUG: CurlFile::ParseAndCorrectUrl() adding custom header option 'x-csrf-token: 53095d2b4fbd32167d405f99282f5c400fbc9ca9'
16:14:31.415 T:2954704960   DEBUG: CurlFile::Open(0xd2c1120) http://127.0.0.1:8090/track/28RFYsTEk5mk6BMdMxfHCr.wav?idx=1
16:14:31.415 T:2954704960   ERROR: CCurlFile::FillBuffer - Failed: Couldn't connect to server(7)
16:14:31.415 T:2954704960   ERROR: CCurlFile::Open failed with code 0 for http://127.0.0.1:8090/track/28RFYsTEk5mk6BMdMxfHCr.wav?idx=1|x-csrf-token=53095d2b4fbd32167d405f99282f5c400fbc9ca9&User-Agent=Spotify%2F1.0.26+%28XBMC%2F17.0-RC3+Git%3A20170112-nogitfound%29
16:14:31.416 T:2954704960   ERROR: Open - failed to open source <http://127.0.0.1:8090/track/28RFYsTEk5mk6BMdMxfHCr.wav?idx=1|x-csrf-token=53095d2b4fbd32167d405f99282f5c400fbc9ca9&User-Agent=Spotify%2F1.0.26+%28XBMC%2F17.0-RC3+Git%3A20170112-nogitfound%29>
16:14:31.416 T:2954704960   ERROR: Init: Error opening file http://127.0.0.1:8090/track/28RFYsTEk5mk6BMdMxfHCr.wav?idx=1|x-csrf-token=53095d2b4fbd32167d405f99282f5c400fbc9ca9&User-Agent=Spotify%2F1.0.26+%28XBMC%2F17.0-RC3+Git%3A20170112-nogitfound%29

It doesn't work with VideoPlayer neither.

I'm using it on Linux with the latest RC from the repo.

[User 224999]

Please report issues with the Spotify addon in the Spotify thread.
Also, looking at your log you're using the wrong version of the Spotify addon, you should have version 1.0.26

[Bad_CRC]

Sorry about that, I just was making a remark about in the last RC3 it still doesn't work.

I've 1.0.26 version though (just checked it in kodi and in the log it says "User-Agent=Spotify%2F1.0.26".

Anyway I'll look in the Spotify thread for a fix, thanks!

[User 224999]

Sorry, I misread the log.. You are indeed using the correct versiuon as the header for the token is correctly applied.
Something else is wrong in that case. It's working fine with me on several systems. Please post your log in the spotify thread and I'll take a look.
Might take me a few days as I'm pretty busy ;-)