starred/go-librespot
1
0
Fork 0
mirror of https://github.com/devgianlu/go-librespot.git synced 2026-04-25 21:05:47 +03:00
Code Issues 22 Projects Releases 5 Packages Wiki Activity

[GH-ISSUE #237] [Bug] "spclient request returned bad gateway" and "context deadline exceeded" when trying connect from desktop/mobile app #148

New issue
Open
opened 2026-02-28 14:25:47 +03:00 by kerem · 1 comment
kerem commented 2026-02-28 14:25:47 +03:00
Owner
Copy link

Originally created by @szygmunt on GitHub (Oct 25, 2025).
Original GitHub issue: https://github.com/devgianlu/go-librespot/issues/237

Hi, I’m having an issue with go-librespot.

After starting the service on my Raspberry Pi, it connects and authenticates fine, but when I try to connect from my android phone it can't pair with go-librespot running on Rpi. The log keeps showing “spclient request returned bad gateway” and finally “context deadline exceeded”.

Here’s the full log from systemd:

paź 25 19:52:32 pi systemd[1]: Started go-librespot.service - go-librespot (open source Spotify client written in Go).
paź 25 19:52:32 pi go-librespot[9037]: time="2025-10-25T19:52:32+02:00" level=info msg="running go-librespot 2f3baebb"
paź 25 19:52:32 pi go-librespot[9037]: level=debug msg="app state loaded"
paź 25 19:52:32 pi go-librespot[9037]: level=info msg="api server listening on 192.168.1.3:3678"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
paź 25 19:52:33 pi go-librespot[9037]: level=info msg="zeroconf server listening on port 45787"
paź 25 19:52:33 pi go-librespot[9037]: level=info msg="loading previously persisted zeroconf credentials" username="sz**********nt"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="obtained new client token: AABkhePVOag5eWxFPO3tpFOlns8bWuQTYGHzbTlw6CaiIVm/+0+BNsknhnpTWGFxKwDPxf0ycDG7h+e++q8NU+CTwMHa10urQFxRQ8wdV7NuH5aZwM09PtJ4TIGl8S1qthmWJvkG0JNkXqwTYyXRWVni8haJHKgYOH5wJUcnx7AILjQKJD3Cf/Z6VPtvnFIL7k9UtoGFT0Ck5Xw4AO0nUJur8O1SSFvbjHfgs0/azA=="
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="connected to ap-gew4.spotify.com:4070"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="completed keyexchange"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="completed challenge"
paź 25 19:52:33 pi go-librespot[9037]: level=info msg="authenticated AP" username="sz**********nt"
paź 25 19:52:33 pi go-librespot[9037]: level=info msg="authenticated Login5" username="sz**********nt"
paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="initializing zeroconf session" username="sz**********nt"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="dealer connection opened"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="received connection id: YmMzM2NhODEtOTBm...MjM5QkIxN0I1Rg=="
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="put connect state because NEW_DEVICE"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="update volume requested to 58981/65535"
paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="put connect state because VOLUME_CHANGED"
paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="handling transfer player command from 53fb062c7800a19dcec70de307dc66f9fa178620"
paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="resolved context of track" uri="spotify:station:track:1ZEOIhSn6BKErV59bIgn76"
paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..."
paź 25 19:53:10 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..."
paź 25 19:53:28 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..."
paź 25 19:53:32 pi go-librespot[9037]: level=error msg="failed fetching next tracks" error="failed moving to next index 50 ... spclient request failed: context deadline exceeded" uri="spotify:station:track:1ZEOIhSn6BKErV59bIgn76"
paź 25 19:53:32 pi go-librespot[9037]: level=debug msg="loading track (paused: true, position: 91860ms)" uri="spotify:track:2ctvdKmETyOzPb2GiJJT53"
paź 25 19:53:32 pi go-librespot[9037]: level=error msg="failed put state after update" error="context deadline exceeded"
paź 25 19:53:32 pi go-librespot[9037]: level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:2ctvdKmETyOzPb2GiJJT53: failed getting track metadata: spclient request failed: context deadline exceeded"

Any idea what might be wrong or how to debug this further?

Originally created by @szygmunt on GitHub (Oct 25, 2025). Original GitHub issue: https://github.com/devgianlu/go-librespot/issues/237 Hi, I’m having an issue with go-librespot. After starting the service on my Raspberry Pi, it connects and authenticates fine, but when I try to connect from my android phone it can't pair with go-librespot running on Rpi. The log keeps showing “spclient request returned bad gateway” and finally “context deadline exceeded”. Here’s the full log from systemd: ``` paź 25 19:52:32 pi systemd[1]: Started go-librespot.service - go-librespot (open source Spotify client written in Go). paź 25 19:52:32 pi go-librespot[9037]: time="2025-10-25T19:52:32+02:00" level=info msg="running go-librespot 2f3baebb" paź 25 19:52:32 pi go-librespot[9037]: level=debug msg="app state loaded" paź 25 19:52:32 pi go-librespot[9037]: level=info msg="api server listening on 192.168.1.3:3678" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" paź 25 19:52:33 pi go-librespot[9037]: level=info msg="zeroconf server listening on port 45787" paź 25 19:52:33 pi go-librespot[9037]: level=info msg="loading previously persisted zeroconf credentials" username="sz**********nt" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="obtained new client token: AABkhePVOag5eWxFPO3tpFOlns8bWuQTYGHzbTlw6CaiIVm/+0+BNsknhnpTWGFxKwDPxf0ycDG7h+e++q8NU+CTwMHa10urQFxRQ8wdV7NuH5aZwM09PtJ4TIGl8S1qthmWJvkG0JNkXqwTYyXRWVni8haJHKgYOH5wJUcnx7AILjQKJD3Cf/Z6VPtvnFIL7k9UtoGFT0Ck5Xw4AO0nUJur8O1SSFvbjHfgs0/azA==" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="connected to ap-gew4.spotify.com:4070" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="completed keyexchange" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="completed challenge" paź 25 19:52:33 pi go-librespot[9037]: level=info msg="authenticated AP" username="sz**********nt" paź 25 19:52:33 pi go-librespot[9037]: level=info msg="authenticated Login5" username="sz**********nt" paź 25 19:52:33 pi go-librespot[9037]: level=debug msg="initializing zeroconf session" username="sz**********nt" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="dealer connection opened" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="received connection id: YmMzM2NhODEtOTBm...MjM5QkIxN0I1Rg==" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="put connect state because NEW_DEVICE" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="update volume requested to 58981/65535" paź 25 19:52:34 pi go-librespot[9037]: level=debug msg="put connect state because VOLUME_CHANGED" paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="handling transfer player command from 53fb062c7800a19dcec70de307dc66f9fa178620" paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="resolved context of track" uri="spotify:station:track:1ZEOIhSn6BKErV59bIgn76" paź 25 19:53:02 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..." paź 25 19:53:10 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..." paź 25 19:53:28 pi go-librespot[9037]: level=debug msg="spclient request returned bad gateway, retrying..." paź 25 19:53:32 pi go-librespot[9037]: level=error msg="failed fetching next tracks" error="failed moving to next index 50 ... spclient request failed: context deadline exceeded" uri="spotify:station:track:1ZEOIhSn6BKErV59bIgn76" paź 25 19:53:32 pi go-librespot[9037]: level=debug msg="loading track (paused: true, position: 91860ms)" uri="spotify:track:2ctvdKmETyOzPb2GiJJT53" paź 25 19:53:32 pi go-librespot[9037]: level=error msg="failed put state after update" error="context deadline exceeded" paź 25 19:53:32 pi go-librespot[9037]: level=warning msg="failed handling dealer request" error="failed loading current track (transfer): failed creating stream for spotify:track:2ctvdKmETyOzPb2GiJJT53: failed getting track metadata: spclient request failed: context deadline exceeded" ``` Any idea what might be wrong or how to debug this further?
kerem commented 2026-02-28 14:25:48 +03:00
Author
Owner
Copy link

@devgianlu commented on GitHub (Nov 4, 2025):

This appears to be a combination of issues:

  • Spotify returns 502 when trying to resolve the list of tracks associated with spotify:station:track:1ZEOIhSn6BKErV59bIgn76
  • We retry 502 responses indefinitely
  • The context for transfer requests expires and everything breaks

I am implementing some workarounds with #246, but I am not closing this. To debug further switch to the latest commit and increase the log level to trace. It should not fail to load anymore, just take ~10 seconds, but the logs should have a bit more insight.

<!-- gh-comment-id:3487089445 --> @devgianlu commented on GitHub (Nov 4, 2025): This appears to be a combination of issues: - Spotify returns 502 when trying to resolve the list of tracks associated with `spotify:station:track:1ZEOIhSn6BKErV59bIgn76` - We retry 502 responses indefinitely - The context for transfer requests expires and everything breaks I am implementing some workarounds with #246, but I am not closing this. To debug further switch to the latest commit and increase the log level to `trace`. It should not fail to load anymore, just take ~10 seconds, but the logs should have a bit more insight.
Sign in to join this conversation.
No Branch/Tag specified
Branches Tags
master
zeroconf-avahi
windows
v0.7.1
v0.7.0
v0.6.2
v0.6.1
v0.6.0
v0.5.3
v0.5.2
v0.5.1
v0.5.0
v0.4.0
v0.3.3
v0.3.2
v0.3.1
v0.3.0
v0.2.0
v0.1.3
v0.1.2
v0.1.1
v0.1.0
v0.0.17.1
v0.0.17
v0.0.16
v0.0.15
v0.0.14
v0.0.13
v0.0.12
v0.0.11
v0.0.10
v0.0.9
v0.0.8
v0.0.7
v0.0.6
v0.0.5
v0.0.4
v0.0.3
v0.0.2
v0.0.1
Labels
Clear labels   
bug

   
enhancement

   
pull-request

Mirrored from GitHub Pull Request

  
spotify-side
No labels
bug
enhancement
pull-request
spotify-side
Milestone
Clear milestone
No items
No milestone
Projects
Clear projects
No items
No project
Assignees
Clear assignees
No assignees
1 participant
Notifications
Due date
The due date is invalid or out of range. Please use the format "yyyy-mm-dd".

No due date set.

Dependencies

No dependencies set.

Reference
starred/go-librespot#148
No description provided.