I've generally had no issues with Spotty, but I've come across something
strange. Trying to play the "Wish I Was Here Sondtrack" playlist by The
Northern Librarian. The playlist plays fine through Spotify app on my
phone. But through Spotty plugin, the playlist loads, but it skips
certain songs, including track 1 (The Shins - So Now What), Tack 8
(Coldplay - Cat Power), Track 9 (Allie Moss - Wait It Out).
Basic logging didn't find any errors, but when I added some extra log
checks, I did get the following:
Code:
--------------------
21-06-26 09:35:02.7750] Slim::Player::StreamingController::_nextTrackReady
(744) 00:04:20:1f:4a:00: nextTrack will be index 0
[21-06-26 09:35:02.7751] Slim::Player::StreamingController::_Stream (1210)
Song queue is now 0
[21-06-26 09:35:02.7752] Slim::Player::StreamingController::_Stream (1213)
00:04:20:1f:4a:00: preparing to stream song index 0
[21-06-26 09:35:02.7753] Slim::Player::Song::open (360)
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7766]
Plugins::Spotty::AccountHelper::purgeAudioCacheAfterXTracks (228) Played 5
song(s) since last audio cache purge.
[21-06-26 09:35:02.7768] Slim::Player::Song::open (384) seek=false time=0
canSeek=2
[21-06-26 09:35:02.7770] Slim::Player::TranscodingHelper::getConvertCommand2
(488) Matched: spt->ogg via: [spotty-x86_64] --enable-volume-normalisation -n
Squeezebox -c "/var/lib/squeezeboxserver/cache/spotty/fce79a84" --single-track
$URL$ --bitrate 320 --disable-discovery --disable-audio-cache --pass-through
[21-06-26 09:35:02.7771] Slim::Player::Song::open (414) Transcoder:
streamMode=R, streamformat=ogg
[21-06-26 09:35:02.7773]
Slim::Player::TranscodingHelper::tokenizeConvertCommand2 (662) Using command
for conversion:
"/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64"
--enable-volume-normalisation -n Squeezebox -c
"/var/lib/squeezeboxserver/cache/spotty/fce79a84" --single-track
"spotify://track:4dQKsoCEGMWr23QZTkoGRp" --bitrate 320 --disable-discovery
--disable-audio-cache --pass-through & |
[21-06-26 09:35:02.7774] Slim::Player::Song::open (567) Tokenized command:
"/var/lib/squeezeboxserver/cache/InstalledPlugins/Plugins/Spotty/Bin/i386-linux/spotty-x86_64"
--enable-volume-normalisation -n Squeezebox -c
"/var/lib/squeezeboxserver/cache/spotty/fce79a84" --single-track
"spotify://track:4dQKsoCEGMWr23QZTkoGRp" --bitrate 320 --disable-discovery
--disable-audio-cache --pass-through & |
[21-06-26 09:35:02.7876] Slim::Player::StreamingController::_Stream (1282)
00:04:20:1f:4a:00: stream
[21-06-26 09:35:02.7881] Slim::Player::Boom::play (342) Setting LineIn to 0
for [spotify://track:4dQKsoCEGMWr23QZTkoGRp]
[21-06-26 09:35:02.7882] Slim::Player::Boom::setLineIn (434) Switching to
line in 0
[21-06-26 09:35:02.7885] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7891] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7896] Plugins::Spotty::ProtocolHandler::getMetadataFor
(201) Found cached metadata for spotify:track:0q6nRzY4uFEudb8072a3CQ
[21-06-26 09:35:02.7899] Slim::Player::StreamingController::_Stream (1319)
Song queue is now 0
[21-06-26 09:35:02.7901] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state BUFFERING
[21-06-26 09:35:02.7901]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state STREAMING
[21-06-26 09:35:02.7905] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7924] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7941] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7952] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.7956] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.8153] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.8171] Plugins::Spotty::ProtocolHandler::getMetadataFor
(181) Returning metadata cached in song object for
spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:02.9142] Slim::Player::Player::_buffering (1144) Buffering...
0 / 20480
[21-06-26 09:35:03.1017] Slim::Player::Source::_readNextChunk (372) end of
file or error on socket, song pos: 5144587
[21-06-26 09:35:03.1021] Slim::Player::Source::_readNextChunk (377)
00:04:20:1f:4a:00 mark end of stream
[21-06-26 09:35:03.1024] Slim::Player::Source::_readNextChunk (385) Didn't
stream any bytes for this song; mark it as failed
[21-06-26 09:35:03.1028]
Slim::Player::StreamingController::playerStreamingFailed (2248)
00:04:20:1f:4a:00
[21-06-26 09:35:03.1033] Slim::Player::StreamingController::_playersMessage
(796) Problem: Can't open file for:: spotify://track:4dQKsoCEGMWr23QZTkoGRp
[21-06-26 09:35:03.1049] Slim::Player::StreamingController::_Stop (610) Song
queue is now 0
[21-06-26 09:35:03.1054] Slim::Player::StreamingController::_setPlayingState
(2377) new playing state STOPPED
[21-06-26 09:35:03.1057]
Slim::Player::StreamingController::_setStreamingState (2386) new streaming
state IDLE
[21-06-26 09:35:03.1061] Slim::Player::StreamingController::_willRetry (1408)
no retry data
[21-06-26 09:35:03.1066] Slim::Player::StreamingController::nextsong (889)
The next song is number 1, was 0
[21-06-26 09:35:03.1073] Slim::Player::Song::new (109) index 1 ->
spotify://track:0q6nRzY4uFEudb8072a3CQ
--------------------
Note the row:
[21-06-26 09:35:03.1017] Slim::Player::Source::_readNextChunk (372) end
of file or error on socket, song pos: 5144587
Any thoughts on what might be happening here?
Things I've done so far:
- Updated to latest LMS nightly build
- made sure all plugins are updated (restarted LMS afterward)
- updated rest of system (Ubuntu Focal on Intel NUC)
- Rebooted full system after updates
My Spotify is on a family account and I set up a developer Spotify
Client ID in the plugin.
Thanks
------------------------------------------------------------------------
wactuary's Profile: http://forums.slimdevices.com/member.php?userid=233
View this thread: http://forums.slimdevices.com/showthread.php?t=114726
_______________________________________________
plugins mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/plugins
