spotify for only ~2 seconds

[fabian727]

Hey guys,

nice software you got here.
I bought one of the louder esp32 with preinstalled squeezelite firmware (I2S-4MFlash-1681).
I can play local media or spotify media from Home/Music Assistant all day long, which is pretty nice.
Bluetooth also nicely works.

If I try to take control from spotify directly, it plays for ~2 seconds, then a short white noise and nothing.
Within the spotify ui, you still can see the track going on and changing volume, but nothing happens.
I still can start music from spotify via MA, but to directly control from spotify, I need to reboot the speaker.

After the error occurs, the device is also listed within spoitfy as "in another network".
As I can still control via MA, I assume it's a problem of squeezelite (configuration) and not cspot.

Who can help?

[fabian727]

Log from starting spotify stream directly:

[08:35:02.570] I MercurySession.cpp:42: Received packet, command: 181
[08:35:02.574] D SpircHandler.cpp:69: Received subscription response
[08:35:02.578] D SpircHandler.cpp:132: Notify frame
[08:35:04.408] I MercurySession.cpp:42: Received packet, command: 181
[08:35:04.412] D SpircHandler.cpp:69: Received subscription response
[08:35:04.418] D SpircHandler.cpp:179: Load frame 2!
[08:35:04.423] D MercurySession.cpp[:252: 08:Executing Mercury Request, type SEND35
:04.435] D MercurySession.cpp:252: Executing Mercury Request, type GET
[08:35:04.447] I TrackPlayer.cpp:98: Resetting state
[08:35:04.475] I MercurySession.cpp:42: Received packet, command: 178
[08:35:04.478] D MercurySession.cpp:174: Received mercury packet
[08:35:04.482] I TrackQueue.cpp:162: Track name: Silence Speaks
[08:35:04.495] I TrackQueue.cpp:163: Track duration: 308916
[08:35:04.498] D TrackQueue.cpp:166: trackInfo.restriction.size() = 1
[08:35:04.512] D TrackQueue.cpp:216: File format: 2
[08:35:04.515] D TrackQueue.cpp:216: File format: 1
[08:35:04.546] I MercurySession.cpp:42: Received packet, command: 178
[08:35:04.549] D MercurySession.cpp:174: Received mercury packet
[08:35:04.604] I MercurySession.cpp:42: Received packet, command: 181
[08:35:04.609] I MercurySession.cpp:42: Received packet, command: 13
[08:35:04.614] I TrackQueue.cpp:256: Got audio key
[08:35:04.617] [08:35:04.628] I MercurySession.cpp:42: Received packet, command: 181I 
TrackQueue.cpp:279: Received access key, fetching CDN URL...
[08:35:04.944] I MercurySession.cpp:42: Received packet, command: 181
[08:35:05.204] I TrackQueue.cpp:305: Received CDN URL, https://audio-ak.spotifycdn.com/audio/e150dc3b5974299dc9fa9b1461d678145a48182d?__token__=exp=1745421737~hmac=f3964abb37052bd9028db201402d967b849946f21b010ee840d88c3e99643e98
[08:35:05.219] I TrackPlayer.cpp:171: Got track ID=e150dc3b5974299dc9fa9b1461d678145a48182d
[08:35:05.233] I CDNAudioFile.cpp:43: Opening HTTP stream to https://audio-ak.spotifycdn.com/audio/e150dc3b5974299dc9fa9b1461d678145a48182d?__token__=exp=1745421737~hmac=f3964abb37052bd9028db201402d967b849946f21b010ee840d88c3e99643e98
[08:35:06.384] I CDNAudioFile.cpp:70: Header and footer bytes received
[08:35:06.389] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:35:06.397] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:35:06.405] cspot_cmd_handler:383 CSpot start track
[08:35:06.406] I Shim.cpp:217: (re)start playing at 6468
[08:35:06.419] cspot_cmd_handler:396 CSpot play
[08:35:06.499] output_thread_i2s:518 Output state is 2
[08:35:06.502] output_thread_i2s:585 Restarting I2S.
[08:35:06.526] I MercurySession.cpp:42: Received packet, command: 178
[08:35:06.531] D MercurySession.cpp:174: Received mercury packet
[08:35:06.740] I MercurySession.cpp:42: Received packet, command: 178
[08:35:06.743] D MercurySession.cpp:174: Received mercury packet
[08:35:07.249] I TrackPlayer.cpp:206: Playing
[08:35:07.252] I Shim.cpp:120: new track started <> => <e150dc3b5974299dc9fa9b1461d678145a48182d>
[08:35:07.265] I Shim.cpp:262: next track will play in 0 ms
[08:35:07.280] _output_frames:150 track start sample rate: 44100 replay_gain: 0
[08:35:07.347] I Shim.cpp:416: next track's audio has reached DAC (offset 6468)
[08:35:07.358] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:35:07.391] D MercurySession.cpp:252: Executing Mercury Request, type SEND
[08:35:07.481] I MercurySession.cpp:42: Received packet, command: 178
[08:35:07.587] D MercurySession.cpp:174: Received mercury packet
[08:35:07.804] I MercurySession.cpp:42: Received packet, command: 178
[08:35:08.021] D MercurySession.cpp:174: Received mercury packet

[fabian727]

Log when started from MusicAssistant

[00:00:38.433] decode_flush:251 decode flush
[00:00:38.434] output_flush_streaming:462 flush output buffer (streaming)
[00:00:38.437] decode_flush:251 decode flush
[00:00:38.438] output_flush:446 flush output buffer (full)
[00:00:38.450] codec_open:283 codec open: 'f'
[00:00:38.453] stream_sock:660 connecting to 192.168.0.3:8097
[00:00:38.459] stream_sock:734 header: GET /single/RTpweUCC/8c:4f:00:1b:06:34/f340c49ff50148d6a9228ef16446d8a2.flac HTTP/1.0
Host: 192.168.0.3:8097
Connection: close
Accept: */*
Cache-Control: no-cache
User-Agent: VLC/3.0.9 LibVLC/3.0.9
Range: bytes=0-


[00:00:38.598] stream_thread:415 headers: len: 335
HTTP/1.0 200 OK
Server: Music Assistant
transferMode.dlna.org: Streaming
contentFeatures.dlna.org: DLNA.ORG_OP=00;DLNA.ORG_CI=0;DLNA.ORG_FLAGS=0d500000000000000000000000000000
Cache-Control: no-cache
Pragma: no-cache
icy-name: The xx - Intro
Accept-Ranges: none
Content-Type: audio/flac
Date: Tue, 22 Apr 2025 15:26:34 GMT


[00:00:38.633] codec_open:283 codec open: 'f'
[00:00:39.460] write_cb:144 setting track_start
[00:00:39.511] output_thread_i2s:518 Output state is 5
[00:00:39.542] _output_frames:150 track start sample rate: 44100 replay_gain: 0
[00:00:39.606] output_thread_i2s:585 Restarting I2S.
[00:00:39.636] output_thread_i2s:518 Output state is 2
[00:00:42.202] I MercurySession.cpp:42: Received packet, command: 181
[00:00:42.206] D SpircHandler.cpp:69: Received subscription response
[00:00:42.210] D SpircHandler.cpp:132: Notify frame