Hi there! I'm using SpotiMC beta4 with XBMC 12 on OpenElec (Zotac AD11 PLUS). I'm experiencing regular random exits of the SpotiMC plugin.
My xbmc log file shows these lines:
Code:
18:54:54 T:140128357947136 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "HEAD /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128357947136 NOTICE: .
18:54:54 T:140128349554432 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128349554432 NOTICE: .
18:54:54 T:140128534128384 NOTICE: Thread CFileCache start, auto delete: false
18:54:54 T:140129728444224 ERROR: Init: Error creating demuxer
18:54:54 T:140128488998656 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128488998656 NOTICE: .
18:54:54 T:140128534128384 NOTICE: Thread CFileCache start, auto delete: false
18:54:54 T:140129728444224 ERROR: ADPCMCodec: error opening file http://127.0.0.1:8080/track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1|X-Spotify-Token=7990d64e26725375ddb2c2515abfcf7bb62ab0dd&User-Agent=Spotimc%2F1.0-beta4+%28XBMC%2F12.2+Git%3A32b1a5e%29!
18:54:54 T:140128408303360 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128408303360 NOTICE: .
18:54:54 T:140128534128384 NOTICE: Thread CFileCache start, auto delete: false
18:54:54 T:140128383125248 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128383125248 NOTICE: .
18:54:54 T:140128534128384 WARNING: FillBuffer: curl failed with code 33
18:54:54 T:140128534128384 ERROR: CCurlFile::CReadState::Open, didn't get any data from stream.
18:54:54 T:140128534128384 ERROR: Process, error 115 seeking. seek returned -1
18:54:54 T:140128374732544 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128374732544 NOTICE: .
18:54:54 T:140128534128384 NOTICE: Thread CFileCache start, auto delete: false
18:54:54 T:140128399910656 NOTICE: 127.0.0.1 - - [09/Aug/2013:18:54:54] "GET /track/1lpN3qsugqtMR49xwzHYnt.wav?idx=1 HTTP/1.1" 200 41101244 "" "Spotimc/1.0-beta4 (XBMC/12.2 Git:32b1a5e)"
18:54:54 T:140128399910656 NOTICE: .
18:54:54 T:140128534128384 WARNING: FillBuffer: curl failed with code 33
18:54:54 T:140128534128384 ERROR: CCurlFile::CReadState::Open, didn't get any data from stream.
18:54:54 T:140128534128384 ERROR: Process, error 115 seeking. seek returned -1
18:54:54 T:140128399910656 ERROR: [09/Aug/2013:18:54:54] Traceback (most recent call last):
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 169, in trap
return func(*args, **kwargs)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 261, in next
return self.iter_response.next()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/httpproxy.py", line 288, in _write_file_content
frame, has_frames = buf.get_frame_wait(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 340, in get_frame_wait
return self.get_frame(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 311, in get_frame
raise BufferError("Frame number #%d gone, too late my friend." % frame_num)
BufferError: Frame number #0 gone, too late my friend.
18:54:54 T:140128399910656 NOTICE: .
18:54:54 T:140128399910656 ERROR: [09/Aug/2013:18:54:54] Traceback (most recent call last):
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 193, in trap
self.start_response(s, h, _sys.exc_info())
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 169, in trap
return func(*args, **kwargs)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 261, in next
return self.iter_response.next()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/httpproxy.py", line 288, in _write_file_content
frame, has_frames = buf.get_frame_wait(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 340, in get_frame_wait
return self.get_frame(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 311, in get_frame
raise BufferError("Frame number #%d gone, too late my friend." % frame_num)
BufferError: Frame number #0 gone, too late my friend.
18:54:54 T:140128399910656 NOTICE: .
18:54:54 T:140128399910656 ERROR: BufferError('Frame number #0 gone, too late my friend.',)
18:54:54 T:140128399910656 NOTICE: .
18:54:54 T:140128374732544 ERROR: [09/Aug/2013:18:54:54] Traceback (most recent call last):
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 169, in trap
return func(*args, **kwargs)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 261, in next
return self.iter_response.next()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/httpproxy.py", line 288, in _write_file_content
frame, has_frames = buf.get_frame_wait(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 340, in get_frame_wait
return self.get_frame(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 311, in get_frame
raise BufferError("Frame number #%d gone, too late my friend." % frame_num)
BufferError: Frame number #49 gone, too late my friend.
18:54:54 T:140128374732544 NOTICE: .
18:54:54 T:140128374732544 ERROR: [09/Aug/2013:18:54:54] Traceback (most recent call last):
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 193, in trap
self.start_response(s, h, _sys.exc_info())
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 169, in trap
return func(*args, **kwargs)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 261, in next
return self.iter_response.next()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/httpproxy.py", line 288, in _write_file_content
frame, has_frames = buf.get_frame_wait(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 340, in get_frame_wait
return self.get_frame(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 311, in get_frame
raise BufferError("Frame number #%d gone, too late my friend." % frame_num)
BufferError: Frame number #49 gone, too late my friend.
18:54:54 T:140128374732544 NOTICE: .
18:54:54 T:140128374732544 ERROR: BufferError('Frame number #49 gone, too late my friend.',)
18:54:54 T:140128374732544 NOTICE: .
18:54:54 T:140128374732544 ERROR: Traceback (most recent call last):
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/wsgiserver/wsgiserver2.py", line 1302, in communicate
req.respond()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/wsgiserver/wsgiserver2.py", line 831, in respond
self.server.gateway(self).respond()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/wsgiserver/wsgiserver2.py", line 2117, in respond
for chunk in response:
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 161, in next
return self.trap(self.iter_response.next)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 193, in trap
self.start_response(s, h, _sys.exc_info())
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 169, in trap
return func(*args, **kwargs)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/CherryPy.egg/cherrypy/_cpwsgi.py", line 261, in next
return self.iter_response.next()
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/httpproxy.py", line 288, in _write_file_content
frame, has_frames = buf.get_frame_wait(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 340, in get_frame_wait
return self.get_frame(frame_num)
File "/storage/.xbmc/addons/script.audio.spotimc/resources/libs/PyspotifyCtypesProxy.egg/spotifyproxy/audio.py", line 311, in get_frame
raise BufferError("Frame number #%d gone, too late my friend." % frame_num)
BufferError: Frame number #49 gone, too late my friend.
18:54:54 T:140128374732544 NOTICE: .
18:54:54 T:140128534128384 WARNING: FillBuffer: curl failed with code 18
18:54:54 T:140128534128384 WARNING: FillBuffer: Reconnect, (re)try 1
[/code]
Any idea what could be going wrong?
And more important: is this fixable?
It is kind of annoying, and it wasn't happening using SpotiMC on XBMC 11.
Hope it is fixable