New 2023 Spotify Plugin

Hey
Can’t make it work, even with this 4.1.1 version
I even reseted the installation.
Here is the log
Thx for your work

Thanks for the log, very useful. We are looking at it, but there won’t be any solution to this before a week.
Can you try with a different track? Does that work? Where are you from?

What a fast answer :slight_smile:
To be more precise, when I choose the volumio device, it connects for 2 sec then disconnects. can’t connect after at all except if I reboot the device or if I disable/enable the plugin.

It’s the same with any track
I’m from Belgium

Take the time you need and don’t hesitate to ask for more info or for any test.
Kr

1 Like

Can you try to select the Volumio device with Spotify in Pause or stop. Then, once connected play.
Let me know

Of course but that’s what I always do.

I checked the log and understand why you ask, the track is identified and seems to play but it’s not and it’s strange…

Works again on my Volumio x86 version by updating to 4.1.1

Awesome work & support!

Thanks a lot :+1:

1 Like

My log looked exactly the same like yours for about a week (running Volumio on my Rpi3). I just updated to v3.758 and updated to Spotify 4.1.1 after Volumio was up again.

Spotify showed the same problem initially, but after a Volumio restart everything seems to work now.

I’m from the Netherlands by the way.

1 Like

Thanks for the quick reqonse. I was wrong w/ my suspect. Anyhow 4.1.1 did fix the issues.

Hi! I just posted the following on a related thread:

Is it possible that the Spotify Plugin may be affecting overall system responsiveness?

Hello. Upon further testing, I’ve constantly run into the following errors on 2 different systems with the current version of the Spotify plugin (4.1.1) and Spotify Connect:

  • Random disconnects
  • Random inability to play selected songs from playlists with over 20 items (for example, a playlist with 30 songs like my weekly recommendations, or another with 3000+ songs I’ve curated over time). When I click on individual items on these lists, or press skip, from either a mobile or desktop Spotify app, I get an error message saying the track can’t be played and the system either selects a random song from it instead, or plays the first song in the playlist

@volumio Will reproduce and send logs.

Hi. Upon testing today, here’s some errors from a live log:

  • When selecting random tracks on a playlist (it could even be the next track from the one currently playing), an error message appears in Spotify saying a track isn’t available for playback. Sometimes the track still plays, sometimes the track skips to another at a random playlist position like back to the beginning of the playlist or 2 tracks down the line, and sometimes the connection to Spotify connect breaks.

When this happens, these messages appear on the live console:

level=info msg=“skipping unplayable media: spotify:track: (track ID)” error=“failed creating stream for spotify:track: (track ID): no supported formats”
time=“2024-09-27T18:03:52-05:00” level=warning msg=“failed handling dealer request” error=“failed skipping to next track: failed creating stream for spotify:track: (track ID): no supported formats”
time=“2024-09-27T18:04:01-05:00” level=debug msg=“fetched chunk 4/21, size: 524288” uri=“spotify:track: (track ID)”
error: error

Other times it’s this:

level=warning msg=“failed handling dealer request” error=“failed loading current track (load context): failed creating stream for spotify:track: (track ID): failed retrieving audio key: failed retrieving aes key with code 2”

And this:
error: Failed request for metavolumio API

Could this be an authentication issue?

Hi All,

I’ve just posted this elsewhere but then found this thread.

I have issues where tracks over 20mins long start to stutter at the 25ish miniute mark. I’m assuming its some kind of cache issue. I can get round the issue i think by dropping the bitrate setting in the plugin down from 320 to 160, but wondered if there was away to fix and use max bitrate still?

Running on a pi3

Cheers

I cannot get the plugin running properly.

What I have:
Volumio 3.757
Spotify 4.1.1
RPi 3

Completely fresh installed Volumio.

My problem:
When I select Volumio in Spotify as Player the Music plays 20-30s and then the Volumio App on macOS/iOS/Windows doesn’t show Volumio as Player anymore so that I cannot control Volumio over Spotify anymore.

What I’ve done so far:
I “played” with the Spotify Browsing but no change.

The interesting thing is: When Volumio changes the song, Spotify is “reconnected” for 1-2s and Spotify shows the same song than Volumio.

Can anybody help? Unfortunately Volspotconnect2 is also not working anymore on 3.757
As for today I would do a downgrade of Volumio to use Volspotconnect2 because this worked perfectly over the past five years.

Greets
Daniel

OK, for now with 3.758 Volspotconnect works again. But the new plugin never worked good here.

1 Like

Hello,

I’ve managed to replicate the track skipping error in albums or playlists with the 4.1.1 plugin. This is using Volumio’s latest version, 3.757.

In this situation, I was playing the “Songs to test headphones with” playlist, here:

The playlist was going fine, until it switched to the 48th track, “Golden” by Jill Scott:

It skipped to Solsbury Hill by Peter Gabriel. And no matter how many times I tried to play the track via Spotify Connect, it would revert to Solsbury Hill. This is what happens, from the console log:

> time="2024-10-09T10:09:13-05:00" level=debug msg="handling play player command from aa915703e95f84a76e51772cffd47e74af73df6d"
> time="2024-10-09T10:09:13-05:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DWZtZ8vUCzche"
> time="2024-10-09T10:09:13-05:00" level=trace msg="fetched new page 0 with 25 items (list: 25)" uri="spotify:playlist:37i9dQZF1DWZtZ8vUCzche"
> time="2024-10-09T10:09:13-05:00" level=warning msg="failed seeking to track in context spotify:playlist:37i9dQZF1DWZtZ8vUCzche" error="could not find track"
> time="2024-10-09T10:09:13-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:13-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
> time="2024-10-09T10:09:13-05:00" level=trace msg="emitting websocket event: will_play"
> SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6vZZtivPrtpggT5SSXsYTy","play_origin":"playlist"}}
> time="2024-10-09T10:09:13-05:00" level=debug msg="selected format OGG_VORBIS_320 (3a74775d743fa8a4f6f900b7f689099e9706bb36)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:13-05:00" level=debug msg="requested aes key for file 3a74775d743fa8a4f6f900b7f689099e9706bb36, gid: 6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=debug msg="fetched first chunk of 23, total size is 11824408 bytes" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=info msg="loaded track \"Solsbury Hill\" (paused: false, position: 0ms, duration: 261560ms, prefetched: false)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
> time="2024-10-09T10:09:14-05:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"

From the log, this error pops up:

time=“2024-10-09T10:09:13-05:00” level=warning msg=“failed seeking to track in context spotify:playlist:37i9dQZF1DWZtZ8vUCzche” error=“could not find track”

When I disconnect from Volumio, I can play the track fine on mobile, desktop, and even spotify connect to different devices that support it (an AVR, for example). Why is this happening with Volumio’s Spotify plugin?

Why are there songs that work on Spotify Connect elsewhere (other devices), everywhere, but not through Volumio?

@volumio @Marco_Melziade @Darmur @balbuze

Here’s another instance, same error, same playlist, different track. Plays well in mobile & desktop apps, and other hardware/software. Not in Volumio.

Oct 09 17:59:41 volumio[992]: SPOTIFY: received: {"type":"volume","data":{"value":31,"max":100}}
Oct 09 17:59:41 volumio[992]: SPOTIFY: RECEIVED SPOTIFY VOLUME 31
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=debug msg="fetched chunk 4/24, size: 524288" uri="spotify:track:1300POeOaj8qho30FRHjgf"
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=debug msg="handling play player command from a9adddb78e0beeee0e6d26dec13b8c4d0776429b"
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1DWZtZ8vUCzche"
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=trace msg="fetched new page 0 with 25 items (list: 25)" uri="spotify:playlist:37i9dQZF1DWZtZ8vUCzche"
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=warning msg="failed seeking to track in context spotify:playlist:37i9dQZF1DWZtZ8vUCzche" error="could not find track"
Oct 09 17:59:45 go-librespot[1234]: time="2024-10-09T17:59:45-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:46 go-librespot[1234]: time="2024-10-09T17:59:46-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Oct 09 17:59:46 go-librespot[1234]: time="2024-10-09T17:59:46-05:00" level=trace msg="emitting websocket event: will_play"
Oct 09 17:59:46 volumio[992]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6vZZtivPrtpggT5SSXsYTy","play_origin":"playlist"}}
Oct 09 17:59:46 go-librespot[1234]: time="2024-10-09T17:59:46-05:00" level=debug msg="selected format OGG_VORBIS_320 (3a74775d743fa8a4f6f900b7f689099e9706bb36)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:46 go-librespot[1234]: time="2024-10-09T17:59:46-05:00" level=debug msg="requested aes key for file 3a74775d743fa8a4f6f900b7f689099e9706bb36, gid: 6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="fetched first chunk of 23, total size is 11824408 bytes" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=info msg="loaded track \"Solsbury Hill\" (paused: false, position: 0ms, duration: 261560ms, prefetched: false)" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="fetched chunk 1/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="fetched chunk 2/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="fetched chunk 3/22, size: 524288" uri="spotify:track:6vZZtivPrtpggT5SSXsYTy"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=trace msg="scheduling prefetch in 231s"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=trace msg="emitting websocket event: metadata"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="sending successful reply for dealer request"
Oct 09 17:59:48 volumio[992]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6vZZtivPrtpggT5SSXsYTy","name":"Solsbury Hill","artist_names":["Peter Gabriel"],"album_name":"Peter Gabriel 1: Car (Remastered)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026b141e77892ab2ccb60cf3cf","position":0,"duration":261560,"release_date":"year:1977","track_number":2,"disc_number":1}}
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED"
Oct 09 17:59:48 go-librespot[1234]: time="2024-10-09T17:59:48-05:00" level=trace msg="emitting websocket event: playing"

Is this tied to the use of librespot for Spotify functionality?

Hey everyone. Spotify’s new AI DJ feature doesn’t work with the Volumio plugin. Should we wait for a fix soon?

Hello, any clues as to what could be causing the Volumio / Librespot error of

level=warning msg=“failed seeking to track in context (spotify:origin or source)” error=“could not find track”

?

Hello!

I have a plugin for Spotify of Version 4.1.1 from Ashthespy, Balbuze, Skirkwood. Volumio of version 3.757 and I have it running on my Raspberry Pi 5 8GB. I noticed a issue that when I connect to the Volumio with Spotify Connect and I click on a certain song, it plays the 1st one in the album. Do you know where could be the problem? Things like skipping songs work perfectly, but I can’t play the song I really want. I have to right click it every time, add it to the queue and skip. Also sometimes it shows on Spotify that It lost connection. But it still plays and works.

1 Like

Hi everyone, go-librespot v0.1.0 has been released which includes lots of changes and possible fixes to the problems you are experiencing.

There have been some breaking changes in the progress, but I am coordinating with Volumio to get this update in the plugin!

7 Likes