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

[GH-ISSUE #1593] Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } #729

New issue
Open
opened 2026-02-27 19:32:10 +03:00 by kerem · 12 comments
kerem commented 2026-02-27 19:32:10 +03:00
Owner
Copy link

Originally created by @arthurus on GitHub (Sep 21, 2025).
Original GitHub issue: https://github.com/librespot-org/librespot/issues/1593

Description

Tracks are being skipped randomly. Sometimes in the middle, usually when advancing to the next track in an album/playlist. For every skipped tracked there is this error:
ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
or this:
ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }

Version

v0.7.1

How to reproduce

Start librespot and play an album/playlist.

Log

[2025-09-21T19:05:33Z INFO  librespot_playback::player] Loading <Sentinels> with Spotify URI <spotify:track:4YOsgTahhiYXmRNjTvpp5d>
[2025-09-21T19:05:33Z DEBUG librespot_audio::fetch] Downloading file 489bed765f0b84f13596aaaf4e38a4059c54ac3c
[2025-09-21T19:05:33Z DEBUG librespot_core::http_client] Requesting https://gew4-spclient.spotify.com:443/storage-resolve/files/audio/interactive/489bed765f0b84f13596aaaf4e38a4059c54ac3c?product=0&country=PL&salt=3287981596
[2025-09-21T19:05:33Z TRACE librespot_core::cdn_url] Resolved CDN storage: CdnUrl {
        file_id: FileId(
            Ok(
                "489bed765f0b84f13596aaaf4e38a4059c54ac3c",
            ),
        ),
        urls: MaybeExpiringUrls(
            [
                MaybeExpiringUrl(
                    "https://audio-fa.scdn.co/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?1758567933_ivZGrhj6vIF6u0_xb8aUyAHhTMKMLLlTt5nzibK-pQM=",
                    Some(
                        Date(
                            2025-09-22 19:00:33.0 +00:00:00,
                        ),
                    ),
                ),
                MaybeExpiringUrl(
                    "https://audio-akp.spotifycdn.com/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?__token__=exp=1758567933~hmac=dff42f4f4c86b40a3a7e292d99df6c26b332e41ef82aefa793b3bffec5e10ee5",
                    Some(
                        Date(
                            2025-09-22 19:00:33.0 +00:00:00,
                        ),
                    ),
                ),
                MaybeExpiringUrl(
                    "https://audio-cf.spotifycdn.com/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?verify=1758567933-PrLxb8%2FfsIYYqr%2BBC3kyZ6tbsWYl8fso7kxq9RaAwT4%3D",
                    Some(
                        Date(
                            2025-09-22 19:00:33.0 +00:00:00,
                        ),
                    ),
                ),
            ],
        ),
    }
[2025-09-21T19:05:34Z TRACE librespot_audio::fetch] Streaming from https://audio-fa.scdn.co/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?1758567933_ivZGrhj6vIF6u0_xb8aUyAHhTMKMLLlTt5nzibK-pQM=
[2025-09-21T19:05:34Z TRACE librespot_audio::fetch::receive] Time to first byte now estimated as: 43 ms
[2025-09-21T19:05:42Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }
[2025-09-21T19:05:42Z DEBUG librespot_playback::player] Unable to preload SpotifyId("spotify:track:4YOsgTahhiYXmRNjTvpp5d")

Host (what you are running librespot on):

  • OS: Debian GNU/Linux 12 (bookworm)
  • Platform: Raspberry Pi Zero 2 W
Originally created by @arthurus on GitHub (Sep 21, 2025). Original GitHub issue: https://github.com/librespot-org/librespot/issues/1593 ### Description Tracks are being skipped randomly. Sometimes in the middle, usually when advancing to the next track in an album/playlist. For every skipped tracked there is this error: `ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")` or this: `ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }` ### Version v0.7.1 ### How to reproduce Start librespot and play an album/playlist. ### Log ``` [2025-09-21T19:05:33Z INFO librespot_playback::player] Loading <Sentinels> with Spotify URI <spotify:track:4YOsgTahhiYXmRNjTvpp5d> [2025-09-21T19:05:33Z DEBUG librespot_audio::fetch] Downloading file 489bed765f0b84f13596aaaf4e38a4059c54ac3c [2025-09-21T19:05:33Z DEBUG librespot_core::http_client] Requesting https://gew4-spclient.spotify.com:443/storage-resolve/files/audio/interactive/489bed765f0b84f13596aaaf4e38a4059c54ac3c?product=0&country=PL&salt=3287981596 [2025-09-21T19:05:33Z TRACE librespot_core::cdn_url] Resolved CDN storage: CdnUrl { file_id: FileId( Ok( "489bed765f0b84f13596aaaf4e38a4059c54ac3c", ), ), urls: MaybeExpiringUrls( [ MaybeExpiringUrl( "https://audio-fa.scdn.co/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?1758567933_ivZGrhj6vIF6u0_xb8aUyAHhTMKMLLlTt5nzibK-pQM=", Some( Date( 2025-09-22 19:00:33.0 +00:00:00, ), ), ), MaybeExpiringUrl( "https://audio-akp.spotifycdn.com/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?__token__=exp=1758567933~hmac=dff42f4f4c86b40a3a7e292d99df6c26b332e41ef82aefa793b3bffec5e10ee5", Some( Date( 2025-09-22 19:00:33.0 +00:00:00, ), ), ), MaybeExpiringUrl( "https://audio-cf.spotifycdn.com/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?verify=1758567933-PrLxb8%2FfsIYYqr%2BBC3kyZ6tbsWYl8fso7kxq9RaAwT4%3D", Some( Date( 2025-09-22 19:00:33.0 +00:00:00, ), ), ), ], ), } [2025-09-21T19:05:34Z TRACE librespot_audio::fetch] Streaming from https://audio-fa.scdn.co/audio/489bed765f0b84f13596aaaf4e38a4059c54ac3c?1758567933_ivZGrhj6vIF6u0_xb8aUyAHhTMKMLLlTt5nzibK-pQM= [2025-09-21T19:05:34Z TRACE librespot_audio::fetch::receive] Time to first byte now estimated as: 43 ms [2025-09-21T19:05:42Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } [2025-09-21T19:05:42Z DEBUG librespot_playback::player] Unable to preload SpotifyId("spotify:track:4YOsgTahhiYXmRNjTvpp5d") ``` ### Host (what you are running `librespot` on): - OS: Debian GNU/Linux 12 (bookworm) - Platform: Raspberry Pi Zero 2 W
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@arthurus commented on GitHub (Sep 21, 2025):

Full log: 1593.log

<!-- gh-comment-id:3316220628 --> @arthurus commented on GitHub (Sep 21, 2025): Full log: [1593.log](https://github.com/user-attachments/files/22454076/1593.log)
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@photovoltex commented on GitHub (Sep 25, 2025):

Hmm, when your reproduction is really just playing a playlist or album, I doubt this might be a general issue, as that's what I'm usually doing. I think this might be in response to an unstable Internet connection or something similar? Were there any problems recently with your network or so?

<!-- gh-comment-id:3332306145 --> @photovoltex commented on GitHub (Sep 25, 2025): Hmm, when your reproduction is really just playing a playlist or album, I doubt this might be a general issue, as that's what I'm usually doing. I think this might be in response to an unstable Internet connection or something similar? Were there any problems recently with your network or so?
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@arthurus commented on GitHub (Sep 25, 2025):

I agree that this must be somehow related to my setup/config/hardware. I've been experiencing it for quite some time (months), also on previous librespot versions (I don't remember which exactly). I don't think it's caused by network issues. I have very stable Internet connection (FTTH) and I use this same RPI for streaming internet radio without any problems. To be sure that it's not related to wi-fi, I disabled it on RPI and used an USB Ethernet adapter. It made no difference.

Like I wrote in the description, it seems to happen randomly. Sometimes, any album I play gets skipped tracks. Other times, it does not occur at all for days, even on the same albums. I have only one album that seems to consistently trigger this issue: https://open.spotify.com/album/1YhJAVWHeN2lif2aFHJYVy. This is actually a single 1 hour long track and so far I was not able to play it from start to finish without this error somewhere in the middle. Example, from just a moment ago:

Sep 25 12:17:24 rpi-zero-2w librespot[1531]: [2025-09-25T10:17:24Z INFO  librespot_playback::player] <Light of Day, Day of Darkness> (3605920 ms) loaded
Sep 25 12:34:27 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:27Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
Sep 25 12:34:27 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:27Z INFO  librespot_connect::spirc] Not playing next track because there are no more tracks left in queue.
Sep 25 12:34:35 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:35Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
Sep 25 12:34:35 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:35Z INFO  librespot_connect::spirc] Not playing next track because there are no more tracks left in queue.

I'm not sure if this is exactly the same issue, because this happens in the middle of a track, not when advancing to a next track, but the error message is the same.

<!-- gh-comment-id:3333466256 --> @arthurus commented on GitHub (Sep 25, 2025): I agree that this must be somehow related to my setup/config/hardware. I've been experiencing it for quite some time (months), also on previous librespot versions (I don't remember which exactly). I don't think it's caused by network issues. I have very stable Internet connection (FTTH) and I use this same RPI for streaming internet radio without any problems. To be sure that it's not related to wi-fi, I disabled it on RPI and used an USB Ethernet adapter. It made no difference. Like I wrote in the description, it seems to happen randomly. Sometimes, any album I play gets skipped tracks. Other times, it does not occur at all for days, even on the same albums. I have only one album that seems to consistently trigger this issue: https://open.spotify.com/album/1YhJAVWHeN2lif2aFHJYVy. This is actually a single 1 hour long track and so far I was not able to play it from start to finish without this error somewhere in the middle. Example, from just a moment ago: ```Sep 25 12:17:19 rpi-zero-2w librespot[1531]: [2025-09-25T10:17:19Z INFO librespot_playback::player] Loading <Light of Day, Day of Darkness> with Spotify URI <spotify:track:6VleSDLbLIty7x4XyUAZjw> Sep 25 12:17:24 rpi-zero-2w librespot[1531]: [2025-09-25T10:17:24Z INFO librespot_playback::player] <Light of Day, Day of Darkness> (3605920 ms) loaded Sep 25 12:34:27 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:27Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }") Sep 25 12:34:27 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:27Z INFO librespot_connect::spirc] Not playing next track because there are no more tracks left in queue. Sep 25 12:34:35 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:35Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:6VleSDLbLIty7x4XyUAZjw")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }") Sep 25 12:34:35 rpi-zero-2w librespot[1531]: [2025-09-25T10:34:35Z INFO librespot_connect::spirc] Not playing next track because there are no more tracks left in queue. ``` I'm not sure if this is exactly the same issue, because this happens in the middle of a track, not when advancing to a next track, but the error message is the same.
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@smogork commented on GitHub (Oct 17, 2025):

@arthurus I have a suggestion. I encounter this error consistently when caching process is too slow (for e.g. my SD card is almost full). Librespot tends to count deadline for downloading and caching together. Here is an example.

2025-10-16T23:26:22+02:00  spotify  [2025-10-16T21:26:22Z INFO  librespot_playback::player] <La Isla Bonita> (242733 ms) loaded
2025-10-16T23:29:06+02:00  spotify  [2025-10-16T21:29:06Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyId("spotify:track:0NJdtoQ3RX5ckBjJlNXhlP")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
2025-10-16T23:29:14+02:00  spotify  [2025-10-16T21:29:14Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyId("spotify:track:0NJdtoQ3RX5ckBjJlNXhlP")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
2025-10-16T23:29:14+02:00  spotify  [2025-10-16T21:29:14Z INFO  librespot_playback::player] Loading <(I Just) Died In Your Arms> with Spotify URI <spotify:track:4ByEFOBuLXpCqvO1kw8Wdm>

In my case, cleaning old cache entries helps for some time.
I can see from logs that you have cache enabled. Could you try to disable it and test this long album?

<!-- gh-comment-id:3414527342 --> @smogork commented on GitHub (Oct 17, 2025): @arthurus I have a suggestion. I encounter this error consistently when caching process is too slow (for e.g. my SD card is almost full). Librespot tends to count deadline for downloading and caching together. Here is an example. ``` 2025-10-16T23:26:22+02:00 spotify [2025-10-16T21:26:22Z INFO librespot_playback::player] <La Isla Bonita> (242733 ms) loaded 2025-10-16T23:29:06+02:00 spotify [2025-10-16T21:29:06Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyId("spotify:track:0NJdtoQ3RX5ckBjJlNXhlP")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }") 2025-10-16T23:29:14+02:00 spotify [2025-10-16T21:29:14Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyId("spotify:track:0NJdtoQ3RX5ckBjJlNXhlP")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }") 2025-10-16T23:29:14+02:00 spotify [2025-10-16T21:29:14Z INFO librespot_playback::player] Loading <(I Just) Died In Your Arms> with Spotify URI <spotify:track:4ByEFOBuLXpCqvO1kw8Wdm> ``` In my case, cleaning old cache entries helps for some time. I can see from logs that you have cache enabled. Could you try to disable it and test this long album?
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@arthurus commented on GitHub (Oct 17, 2025):

@smogork I have audio cache disabled (--disable-audio-cache). The --cache parameter is only to specify where the cached credentials should be stored. I also though that this could be caused by slow SD card access so I replaced it with a new, fast card but it didn't make a difference. However, I just noticed that the --tmp directory (temporary file download location) was not on the SD card but on a very small tmpfs... I changed it and this seems to help - I was able to play the album I mentioned earlier without any errors. I'll keep testing and, hopefully, close this issue if it stops happening.

<!-- gh-comment-id:3414876274 --> @arthurus commented on GitHub (Oct 17, 2025): @smogork I have audio cache disabled (`--disable-audio-cache`). The `--cache` parameter is only to specify where the cached credentials should be stored. I also though that this could be caused by slow SD card access so I replaced it with a new, fast card but it didn't make a difference. However, I just noticed that the `--tmp` directory (temporary file download location) was not on the SD card but on a very small tmpfs... I changed it and this seems to help - I was able to play the album I mentioned earlier without any errors. I'll keep testing and, hopefully, close this issue if it stops happening.
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@arthurus commented on GitHub (Oct 27, 2025):

Closing because it stopped occurring since I changed the --tmp directory.

<!-- gh-comment-id:3450402336 --> @arthurus commented on GitHub (Oct 27, 2025): Closing because it stopped occurring since I changed the `--tmp` directory.
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@Semmu commented on GitHub (Dec 3, 2025):

I just faced the same issue when trying to play a long track (1hr+). It works using the official clients of course.

This is the track itself: https://open.spotify.com/track/1M2Bynkts9pH3wHprdwAsi

And these are the logs:

[2025-12-03T16:48:00Z INFO  librespot_playback::player] Loading <Late Night Tales: Floating Points - Continuous Mix> with Spotify URI <spotify:track:1M2Bynkts9pH3wHprdwAsi>
[2025-12-03T16:48:05Z ERROR librespot_audio::fetch::receive] Streamer error requesting range 169941980 +65140: Error { kind: Unknown, error: hyper::Error(Body, Custom { kind: UnexpectedEof, error: IncompleteBody }) }
[2025-12-03T16:48:08Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }
[2025-12-03T16:48:08Z ERROR librespot_playback::player] Skipping to next track, unable to load track <SpotifyUri("spotify:track:56mA2l0FyXEMV7WYawE4Bi")>: ()
[2025-12-03T16:48:08Z INFO  librespot_connect::spirc] Not playing next track because there are no more tracks left in queue.

Using librespot v0.8.0, in a container, built from source, started with this command:

 /bin/librespot \
        --disable-discovery \
        --enable-oauth \
        --name Szobakocka \
        --bitrate 320 \
        --cache /cache \
        --disable-audio-cache

Now I will experiment with the --tmp flag as well, but I thought to mention that this issue may still be relevant.

Could this be related to some timeout issue caused by long tracks? Maybe those need more time to load sufficient amount of audio data, dunno.

<!-- gh-comment-id:3607850598 --> @Semmu commented on GitHub (Dec 3, 2025): I just faced the same issue when trying to play a long track (1hr+). It works using the official clients of course. This is the track itself: https://open.spotify.com/track/1M2Bynkts9pH3wHprdwAsi And these are the logs: ``` [2025-12-03T16:48:00Z INFO librespot_playback::player] Loading <Late Night Tales: Floating Points - Continuous Mix> with Spotify URI <spotify:track:1M2Bynkts9pH3wHprdwAsi> [2025-12-03T16:48:05Z ERROR librespot_audio::fetch::receive] Streamer error requesting range 169941980 +65140: Error { kind: Unknown, error: hyper::Error(Body, Custom { kind: UnexpectedEof, error: IncompleteBody }) } [2025-12-03T16:48:08Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } [2025-12-03T16:48:08Z ERROR librespot_playback::player] Skipping to next track, unable to load track <SpotifyUri("spotify:track:56mA2l0FyXEMV7WYawE4Bi")>: () [2025-12-03T16:48:08Z INFO librespot_connect::spirc] Not playing next track because there are no more tracks left in queue. ``` Using librespot v0.8.0, in a container, built from source, started with this command: ``` /bin/librespot \ --disable-discovery \ --enable-oauth \ --name Szobakocka \ --bitrate 320 \ --cache /cache \ --disable-audio-cache ``` Now I will experiment with the `--tmp` flag as well, but I thought to mention that this issue may still be relevant. Could this be related to some timeout issue caused by long tracks? Maybe those need more time to load sufficient amount of audio data, dunno.
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@arthurus commented on GitHub (Dec 4, 2025):

@Semmu I recently started seeing this again as well, after having to update librespot because of #1623. I didn't change any configuration, only updated to a newer version. I have no idea if this is a regression or the result of some change on Spotify's side.

<!-- gh-comment-id:3610801367 --> @arthurus commented on GitHub (Dec 4, 2025): @Semmu I recently started seeing this again as well, after having to update librespot because of #1623. I didn't change any configuration, only updated to a newer version. I have no idea if this is a regression or the result of some change on Spotify's side.
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@hbsagen commented on GitHub (Dec 10, 2025):

I got this today as well.

[2025-12-10T17:58:36Z INFO librespot_playback::player] (472400 ms) loaded
[2025-12-10T18:00:34Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:188s885TL0EgDz07BShopC")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
[2025-12-10T18:00:42Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:188s885TL0EgDz07BShopC")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")

<!-- gh-comment-id:3638327007 --> @hbsagen commented on GitHub (Dec 10, 2025): I got this today as well. [2025-12-10T17:58:36Z INFO librespot_playback::player] <The Queen Of All Everything> (472400 ms) loaded [2025-12-10T18:00:34Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:188s885TL0EgDz07BShopC")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }") [2025-12-10T18:00:42Z ERROR librespot_playback::player] Skipping to next track, unable to get next packet for track <SpotifyUri("spotify:track:188s885TL0EgDz07BShopC")>: SymphoniaDecoder("Deadline expired before operation could complete { wait timeout exceeded }")
kerem commented 2026-02-27 19:32:10 +03:00
Author
Owner
Copy link

@Semmu commented on GitHub (Dec 10, 2025):

Yeah today it started happening quite frequently, once an hour or so. librespot seems to handle them by skipping to the next song, but it's still concerning.

I also had librespot crash on me today with these logs:

[2025-12-10T17:46:45Z INFO  librespot_playback::player] Loading <I’m The President> with Spotify URI <spotify:track:7H8mxTjF6Dhgvgx0LlhACG>
[2025-12-10T17:46:45Z INFO  librespot_playback::player] <I’m The President> (247000 ms) loaded
[2025-12-10T17:47:50Z ERROR librespot_core::session] Connection to server closed.
[2025-12-10T17:50:52Z WARN  librespot_connect::spirc] unexpected shutdown
[2025-12-10T17:50:52Z INFO  librespot_playback::player] Loading <Dissolve In The Rain> with Spotify URI <spotify:track:7s3O0xgmkbKGJDPHH0K6Sg>
[2025-12-10T17:50:52Z WARN  librespot_connect::state::context] couldn't load context info because: context is not available. type: Default
[2025-12-10T17:50:52Z ERROR librespot_core::session] Broken pipe (os error 32)
[2025-12-10T17:50:52Z WARN  librespot] Spirc shut down unexpectedly
[2025-12-10T17:50:53Z INFO  librespot_core::session] Connecting to AP "ap-gew1.spotify.com:4070"
[2025-12-10T17:50:53Z INFO  librespot_core::session] Authenticated as 'semmunet' !
[2025-12-10T17:50:53Z INFO  librespot_core::session] Country: "HU"
[2025-12-10T17:50:53Z INFO  librespot_core::spclient] Resolved "gew1-spclient.spotify.com:443" as spclient access point
[2025-12-10T17:50:53Z INFO  librespot_connect::spirc] active device is <> with session <13853891530aa638e4ea1174462e823b>

So something is definitely going on again at Spotify and it is breaking our client I guess.

<!-- gh-comment-id:3639287738 --> @Semmu commented on GitHub (Dec 10, 2025): Yeah today it started happening quite frequently, once an hour or so. librespot seems to handle them by skipping to the next song, but it's still concerning. I also had librespot crash on me today with these logs: ``` [2025-12-10T17:46:45Z INFO librespot_playback::player] Loading <I’m The President> with Spotify URI <spotify:track:7H8mxTjF6Dhgvgx0LlhACG> [2025-12-10T17:46:45Z INFO librespot_playback::player] <I’m The President> (247000 ms) loaded [2025-12-10T17:47:50Z ERROR librespot_core::session] Connection to server closed. [2025-12-10T17:50:52Z WARN librespot_connect::spirc] unexpected shutdown [2025-12-10T17:50:52Z INFO librespot_playback::player] Loading <Dissolve In The Rain> with Spotify URI <spotify:track:7s3O0xgmkbKGJDPHH0K6Sg> [2025-12-10T17:50:52Z WARN librespot_connect::state::context] couldn't load context info because: context is not available. type: Default [2025-12-10T17:50:52Z ERROR librespot_core::session] Broken pipe (os error 32) [2025-12-10T17:50:52Z WARN librespot] Spirc shut down unexpectedly [2025-12-10T17:50:53Z INFO librespot_core::session] Connecting to AP "ap-gew1.spotify.com:4070" [2025-12-10T17:50:53Z INFO librespot_core::session] Authenticated as 'semmunet' ! [2025-12-10T17:50:53Z INFO librespot_core::session] Country: "HU" [2025-12-10T17:50:53Z INFO librespot_core::spclient] Resolved "gew1-spclient.spotify.com:443" as spclient access point [2025-12-10T17:50:53Z INFO librespot_connect::spirc] active device is <> with session <13853891530aa638e4ea1174462e823b> ``` So something is definitely going on again at Spotify and it is breaking our client I guess.
kerem commented 2026-02-27 19:32:11 +03:00
Author
Owner
Copy link

@Semmu commented on GitHub (Dec 11, 2025):

Even more new errors I haven't seen before. Unfortunately librespot is getting really unreliable again.

[2025-12-11T03:15:19Z INFO  librespot_playback::player] Loading <Journey's End> with Spotify URI <spotify:track:1ueKNH4LNFeSUxi0TxYvf2>
[2025-12-11T03:15:21Z ERROR librespot_core::audio_key] Audio key response timeout
[2025-12-11T03:15:21Z WARN  librespot_playback::player] Unable to load key, continuing without decryption: Operation aborted { audio key response timeout }
[2025-12-11T03:15:25Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: end of stream
[2025-12-11T03:15:25Z INFO  librespot_playback::player] Loading <Through Sunflower Fields> with Spotify URI <spotify:track:38zWI9aCGKyRFPX16N89Fw>
[2025-12-11T03:15:26Z ERROR librespot_core::audio_key] Audio key response timeout
[2025-12-11T03:15:26Z WARN  librespot_playback::player] Unable to load key, continuing without decryption: Operation aborted { audio key response timeout }
[2025-12-11T03:15:35Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: end of stream

And these:

[2025-12-11T03:15:43Z WARN  librespot_core::dealer] Websocket peer does not respond.
[2025-12-11T03:15:44Z INFO  librespot_connect::spirc] active device is <71e61713e30265f6d6d8e8b688c97c3e45ce1f52> with session <42d716f53842e15f6f43a13107f3c8f9>
[2025-12-11T03:15:48Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }
[2025-12-11T03:15:48Z WARN  librespot_connect::spirc] unexpected shutdown
[2025-12-11T03:15:48Z WARN  librespot_connect::state::context] couldn't load context info because: context is not available. type: Default
[2025-12-11T03:15:48Z INFO  librespot_playback::player] Loading <Silt Sunrise> with Spotify URI <spotify:track:175IW97bygvL3xomKYOUGe>
[2025-12-11T03:15:49Z WARN  librespot] Spirc shut down unexpectedly

Seems maintaining librespot will be a neverending effort.

<!-- gh-comment-id:3639902299 --> @Semmu commented on GitHub (Dec 11, 2025): Even more new errors I haven't seen before. Unfortunately librespot is getting really unreliable again. ``` [2025-12-11T03:15:19Z INFO librespot_playback::player] Loading <Journey's End> with Spotify URI <spotify:track:1ueKNH4LNFeSUxi0TxYvf2> [2025-12-11T03:15:21Z ERROR librespot_core::audio_key] Audio key response timeout [2025-12-11T03:15:21Z WARN librespot_playback::player] Unable to load key, continuing without decryption: Operation aborted { audio key response timeout } [2025-12-11T03:15:25Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: end of stream [2025-12-11T03:15:25Z INFO librespot_playback::player] Loading <Through Sunflower Fields> with Spotify URI <spotify:track:38zWI9aCGKyRFPX16N89Fw> [2025-12-11T03:15:26Z ERROR librespot_core::audio_key] Audio key response timeout [2025-12-11T03:15:26Z WARN librespot_playback::player] Unable to load key, continuing without decryption: Operation aborted { audio key response timeout } [2025-12-11T03:15:35Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: end of stream ``` And these: ``` [2025-12-11T03:15:43Z WARN librespot_core::dealer] Websocket peer does not respond. [2025-12-11T03:15:44Z INFO librespot_connect::spirc] active device is <71e61713e30265f6d6d8e8b688c97c3e45ce1f52> with session <42d716f53842e15f6f43a13107f3c8f9> [2025-12-11T03:15:48Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } [2025-12-11T03:15:48Z WARN librespot_connect::spirc] unexpected shutdown [2025-12-11T03:15:48Z WARN librespot_connect::state::context] couldn't load context info because: context is not available. type: Default [2025-12-11T03:15:48Z INFO librespot_playback::player] Loading <Silt Sunrise> with Spotify URI <spotify:track:175IW97bygvL3xomKYOUGe> [2025-12-11T03:15:49Z WARN librespot] Spirc shut down unexpectedly ``` Seems maintaining librespot will be a neverending effort.
kerem commented 2026-02-27 19:32:11 +03:00
Author
Owner
Copy link

@Semmu commented on GitHub (Jan 11, 2026):

FYI still seeing these issues pop up now and then.

[2026-01-11T16:19:05Z INFO  librespot_playback::player] Loading <Ark> with Spotify URI <spotify:track:25YGk04mE0QyKs46svac1V>
[2026-01-11T16:19:14Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }
[2026-01-11T16:19:14Z INFO  librespot_playback::player] Loading <Sleepy Pietro> with Spotify URI <spotify:track:0MoeShvEacFvWAROMIfqKz>
[2026-01-11T16:19:23Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded }
[2026-01-11T16:19:23Z INFO  librespot_playback::player] Loading <Sonder> with Spotify URI <spotify:track:3DmBYSSi7oFbsARHIUKmVz>
[2026-01-11T16:19:24Z INFO  librespot_playback::player] <Sonder> (163652 ms) loaded

I'm running librespot on a fairly old and low-powered SBC (Odroid XU4), though it is barely under any load. Maybe Spotify CDNs are sometimes just slow to respond? Anyways I feel like increasing the wait timeout should solve these issues, as retrying the same track usually succeeds!

<!-- gh-comment-id:3734946110 --> @Semmu commented on GitHub (Jan 11, 2026): FYI still seeing these issues pop up now and then. ``` [2026-01-11T16:19:05Z INFO librespot_playback::player] Loading <Ark> with Spotify URI <spotify:track:25YGk04mE0QyKs46svac1V> [2026-01-11T16:19:14Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } [2026-01-11T16:19:14Z INFO librespot_playback::player] Loading <Sleepy Pietro> with Spotify URI <spotify:track:0MoeShvEacFvWAROMIfqKz> [2026-01-11T16:19:23Z ERROR librespot_playback::player] Unable to read audio file: Symphonia Decoder Error: Deadline expired before operation could complete { wait timeout exceeded } [2026-01-11T16:19:23Z INFO librespot_playback::player] Loading <Sonder> with Spotify URI <spotify:track:3DmBYSSi7oFbsARHIUKmVz> [2026-01-11T16:19:24Z INFO librespot_playback::player] <Sonder> (163652 ms) loaded ``` I'm running librespot on a fairly old and low-powered SBC ([Odroid XU4](https://wiki.odroid.com/odroid-xu4/hardware/hardware)), though it is barely under any load. Maybe Spotify CDNs are sometimes just slow to respond? Anyways I feel like increasing the wait timeout should solve these issues, as retrying the same track usually succeeds!
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#729
No description provided.