starred/librespot
1
0
Fork 0
mirror of https://github.com/librespot-org/librespot.git synced 2026-04-27 08:15:50 +03:00
Code Issues 95 Projects Releases 10 Packages Wiki Activity

[GH-ISSUE #1321] Playback failures #602

New issue
Closed
opened 2026-02-27 19:31:31 +03:00 by kerem · 1 comment
kerem commented 2026-02-27 19:31:31 +03:00
Owner
Copy link

Originally created by @kieransouth on GitHub (Sep 2, 2024).
Original GitHub issue: https://github.com/librespot-org/librespot/issues/1321

Describe the bug
Librespot playback no longer working on any song.

To reproduce
Steps to reproduce the behavior:

  1. Launch librespot --cache . --verbose
  2. Open Spotify desktop and choose Librespot as the output device
  3. See errors as the songs fail to load

Log

[2024-09-02T10:24:05Z INFO  librespot] librespot 0.4.2 UNKNOWN (Built on 2024-08-19, Build ID: S2mLXqG9, Profile: release)
[2024-09-02T10:24:05Z TRACE librespot] Command line argument(s):
[2024-09-02T10:24:05Z TRACE librespot]          cache "."
[2024-09-02T10:24:05Z TRACE librespot]          verbose
[2024-09-02T10:24:05Z TRACE librespot] Using cached credentials.
[2024-09-02T10:24:05Z DEBUG librespot_discovery::server] Zeroconf server listening on 0.0.0.0:52451
[2024-09-02T10:24:05Z INFO  librespot_core::session] Connecting to AP "ap2-gew1.spotify.com:4070"
[2024-09-02T10:24:05Z INFO  librespot_core::session] Authenticated as "<redacted>" !
[2024-09-02T10:24:05Z DEBUG librespot_core::session] new Session[0]
[2024-09-02T10:24:05Z INFO  librespot_playback::mixer::softmixer] Mixing with softvol and volume control: Log(60.0)
[2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] new Spirc[0]
[2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] canonical_username: <redacted>
[2024-09-02T10:24:05Z DEBUG librespot_playback::player] new Player[0]
[2024-09-02T10:24:05Z DEBUG librespot::component] new MercuryManager
[2024-09-02T10:24:05Z INFO  librespot_playback::convert] Converting with ditherer: tpdf
[2024-09-02T10:24:05Z DEBUG librespot_playback::mixer::mappings] Input volume 6527 mapped to: 0.20%
[2024-09-02T10:24:05Z INFO  librespot_playback::audio_backend::rodio] Using Rodio sink with format S16 and cpal host: WASAPI
[2024-09-02T10:24:05Z DEBUG librespot_core::session] Session[0] strong=3 weak=2
[2024-09-02T10:24:05Z INFO  librespot_core::session] Country: "GB"
[2024-09-02T10:24:05Z INFO  librespot_playback::audio_backend::rodio] Using audio device: Speakers (HyperX QuadCast S)
[2024-09-02T10:24:05Z DEBUG librespot_playback::audio_backend::rodio] Rodio sink was created
[2024-09-02T10:24:05Z DEBUG librespot_playback::player] command=AddEventSender
[2024-09-02T10:24:05Z DEBUG librespot_playback::player] command=VolumeSet(6527)
[2024-09-02T10:24:05Z DEBUG librespot_core::mercury] subscribed uri=hm://remote/user/<redacted>/ count=0
[2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusStop
[2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPlay
[2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusStop
[2024-09-02T10:24:11Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPause
[2024-09-02T10:24:11Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPause
[2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] kMessageTypeLoad <redacted> kPlayStatusPause
[2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] State: context_uri: "<redacted>" index: 10 position_ms: 110539 status: kPlayStatusPause position_measured_at: 1725272652937 context_description: "" shuffle: false repeat: false playing_from_fallback: true row: 0 playing_track_index: 10 ...
[2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] Frame has 34 tracks
[2024-09-02T10:24:13Z DEBUG librespot_playback::player] command=SetAutoNormaliseAsAlbum(false)
[2024-09-02T10:24:13Z TRACE librespot_connect::spirc] Sending status to server: [kPlayStatusPause]
[2024-09-02T10:24:13Z DEBUG librespot_playback::player] command=Load(SpotifyId { id: 271952954756503356049650030780153736814, audio_type: Track }, false, 110539)
[2024-09-02T10:24:13Z TRACE librespot_connect::spirc] Sending status to server: [kPlayStatusPause]
[2024-09-02T10:24:13Z INFO  librespot_playback::player] Loading <I Want It That Way> with Spotify URI <spotify:track:6e40mgJiCid5HRAGrbpGA6>
[2024-09-02T10:24:13Z DEBUG librespot_audio::fetch] Downloading file 9d29eaf0d2bb408f86314aa77b8c64fef6e113a2
[2024-09-02T10:24:13Z DEBUG librespot::component] new ChannelManager
[2024-09-02T10:24:13Z ERROR librespot_core::channel] channel error: 2 0
[2024-09-02T10:24:13Z ERROR librespot_playback::player] Unable to load encrypted file: ChannelError
[2024-09-02T10:24:13Z WARN  librespot_playback::player] Skipping to next track, unable to load track <SpotifyId { id: 271952954756503356049650030780153736814, audio_type: Track }>: ()

Host (what you are running librespot on):

  • OS: Windows 11 Pro (also tested inside a Dockerfile on Linux)

Additional context
I omitted some info that isn't relevant in the log, but the last 4 lines are the useful bits.
Note that while my output device is a microphone, I have my headphones plugged into it, so it does work as an output device. The error is also present when using the pipe backend inside a Docker container on Linux.
I authenticated librespot by choosing it as the output device on Spotify desktop. It then spams the console window as it fails to play every song in the queue.

Originally created by @kieransouth on GitHub (Sep 2, 2024). Original GitHub issue: https://github.com/librespot-org/librespot/issues/1321 **Describe the bug** Librespot playback no longer working on any song. **To reproduce** Steps to reproduce the behavior: 1. Launch `librespot --cache . --verbose` 2. Open Spotify desktop and choose Librespot as the output device 3. See errors as the songs fail to load **Log** ```librespot --cache . --verbose [2024-09-02T10:24:05Z INFO librespot] librespot 0.4.2 UNKNOWN (Built on 2024-08-19, Build ID: S2mLXqG9, Profile: release) [2024-09-02T10:24:05Z TRACE librespot] Command line argument(s): [2024-09-02T10:24:05Z TRACE librespot] cache "." [2024-09-02T10:24:05Z TRACE librespot] verbose [2024-09-02T10:24:05Z TRACE librespot] Using cached credentials. [2024-09-02T10:24:05Z DEBUG librespot_discovery::server] Zeroconf server listening on 0.0.0.0:52451 [2024-09-02T10:24:05Z INFO librespot_core::session] Connecting to AP "ap2-gew1.spotify.com:4070" [2024-09-02T10:24:05Z INFO librespot_core::session] Authenticated as "<redacted>" ! [2024-09-02T10:24:05Z DEBUG librespot_core::session] new Session[0] [2024-09-02T10:24:05Z INFO librespot_playback::mixer::softmixer] Mixing with softvol and volume control: Log(60.0) [2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] new Spirc[0] [2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] canonical_username: <redacted> [2024-09-02T10:24:05Z DEBUG librespot_playback::player] new Player[0] [2024-09-02T10:24:05Z DEBUG librespot::component] new MercuryManager [2024-09-02T10:24:05Z INFO librespot_playback::convert] Converting with ditherer: tpdf [2024-09-02T10:24:05Z DEBUG librespot_playback::mixer::mappings] Input volume 6527 mapped to: 0.20% [2024-09-02T10:24:05Z INFO librespot_playback::audio_backend::rodio] Using Rodio sink with format S16 and cpal host: WASAPI [2024-09-02T10:24:05Z DEBUG librespot_core::session] Session[0] strong=3 weak=2 [2024-09-02T10:24:05Z INFO librespot_core::session] Country: "GB" [2024-09-02T10:24:05Z INFO librespot_playback::audio_backend::rodio] Using audio device: Speakers (HyperX QuadCast S) [2024-09-02T10:24:05Z DEBUG librespot_playback::audio_backend::rodio] Rodio sink was created [2024-09-02T10:24:05Z DEBUG librespot_playback::player] command=AddEventSender [2024-09-02T10:24:05Z DEBUG librespot_playback::player] command=VolumeSet(6527) [2024-09-02T10:24:05Z DEBUG librespot_core::mercury] subscribed uri=hm://remote/user/<redacted>/ count=0 [2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusStop [2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPlay [2024-09-02T10:24:05Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusStop [2024-09-02T10:24:11Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPause [2024-09-02T10:24:11Z DEBUG librespot_connect::spirc] kMessageTypeNotify <redacted> kPlayStatusPause [2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] kMessageTypeLoad <redacted> kPlayStatusPause [2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] State: context_uri: "<redacted>" index: 10 position_ms: 110539 status: kPlayStatusPause position_measured_at: 1725272652937 context_description: "" shuffle: false repeat: false playing_from_fallback: true row: 0 playing_track_index: 10 ... [2024-09-02T10:24:13Z DEBUG librespot_connect::spirc] Frame has 34 tracks [2024-09-02T10:24:13Z DEBUG librespot_playback::player] command=SetAutoNormaliseAsAlbum(false) [2024-09-02T10:24:13Z TRACE librespot_connect::spirc] Sending status to server: [kPlayStatusPause] [2024-09-02T10:24:13Z DEBUG librespot_playback::player] command=Load(SpotifyId { id: 271952954756503356049650030780153736814, audio_type: Track }, false, 110539) [2024-09-02T10:24:13Z TRACE librespot_connect::spirc] Sending status to server: [kPlayStatusPause] [2024-09-02T10:24:13Z INFO librespot_playback::player] Loading <I Want It That Way> with Spotify URI <spotify:track:6e40mgJiCid5HRAGrbpGA6> [2024-09-02T10:24:13Z DEBUG librespot_audio::fetch] Downloading file 9d29eaf0d2bb408f86314aa77b8c64fef6e113a2 [2024-09-02T10:24:13Z DEBUG librespot::component] new ChannelManager [2024-09-02T10:24:13Z ERROR librespot_core::channel] channel error: 2 0 [2024-09-02T10:24:13Z ERROR librespot_playback::player] Unable to load encrypted file: ChannelError [2024-09-02T10:24:13Z WARN librespot_playback::player] Skipping to next track, unable to load track <SpotifyId { id: 271952954756503356049650030780153736814, audio_type: Track }>: () ``` **Host (what you are running `librespot` on):** - OS: Windows 11 Pro (also tested inside a Dockerfile on Linux) **Additional context** I omitted some info that isn't relevant in the log, but the last 4 lines are the useful bits. Note that while my output device is a microphone, I have my headphones plugged into it, so it does work as an output device. The error is also present when using the pipe backend inside a Docker container on Linux. I authenticated librespot by choosing it as the output device on Spotify desktop. It then spams the console window as it fails to play every song in the queue.
kerem 2026-02-27 19:31:31 +03:00
  • closed this issue
  • added the
    bug
         label
kerem commented 2026-02-27 19:31:32 +03:00
Author
Owner
Copy link

@kieransouth commented on GitHub (Sep 2, 2024):

Fixed by blocking in hosts file as per https://github.com/librespot-org/librespot/issues/972

<!-- gh-comment-id:2324452126 --> @kieransouth commented on GitHub (Sep 2, 2024): Fixed by blocking in hosts file as per https://github.com/librespot-org/librespot/issues/972
Sign in to join this conversation.
No Branch/Tag specified
Branches Tags
dev
v0.8.0
v0.7.1
v0.7.0
v0.6.0
v0.5.0
v0.4.2
v0.4.1
v0.4.0
v0.3.1
v0.3.0
v0.2.0
v0.1.6
v0.1.5
v0.1.3
v0.1.2
v0.1.1
v0.1.0
Labels
Clear labels   
A-Alsa

   
SpotifyAPI

   
Tokio 1.0

   
audio

   
bug

   
can't reproduce

   
compilation

   
dependencies

   
duplicate

   
enhancement

   
good first issue

   
help wanted

   
high priority

   
imported

   
imported

   
invalid

   
new api

   
pull-request

Mirrored from GitHub Pull Request

  
question

   
reverse engineering

   
wiki

   
wontfix
No labels
A-Alsa
SpotifyAPI
Tokio 1.0
audio
bug
can't reproduce
compilation
dependencies
duplicate
enhancement
good first issue
help wanted
high priority
imported
imported
invalid
new api
pull-request
question
reverse engineering
wiki
wontfix
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/librespot#602
No description provided.