[GH-ISSUE #126] Occasional crashes #99

Closed
opened 2026-02-27 19:28:49 +03:00 by kerem · 2 comments
Owner

Originally created by @matemoln on GitHub (Feb 7, 2018).
Original GitHub issue: https://github.com/librespot-org/librespot/issues/126

Sometimes playback stucks and librespot restarts every time I try to play a track. The only way to stop it from restarting is to restart the iOS client.
Here are the logs:

Feb  7 23:30:23 Raspberry librespot[1017]: INFO:librespot::player: Track "Downtown Beat" loaded
Feb  7 23:30:32 Raspberry librespot[1017]: INFO:librespot::player: Loading track "Life Is Like - Original Mix"
Feb  7 23:30:32 Raspberry librespot[1017]: INFO:librespot::player: Track "Life Is Like - Original Mix" loaded
Feb  7 23:31:15 Raspberry librespot[1017]: thread 'main' panicked at 'assertion failed: `(left == right)`
Feb  7 23:31:15 Raspberry librespot[1017]: left: `0`,
Feb  7 23:31:15 Raspberry librespot[1017]: right: `16`', core/src/util/spotify_id.rs:46:8
Feb  7 23:31:15 Raspberry librespot[1017]: note: Run with `RUST_BACKTRACE=1` for a backtrace.
Feb  7 23:31:15 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a
Feb  7 23:31:15 Raspberry systemd[1]: Unit raspotify.service entered failed state.
Feb  7 23:31:25 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart.
Feb  7 23:31:25 Raspberry systemd[1]: Stopping Raspotify...
Feb  7 23:31:25 Raspberry systemd[1]: Starting Raspotify...
Feb  7 23:31:25 Raspberry systemd[1]: Started Raspotify.
Feb  7 23:31:25 Raspberry librespot[4317]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY
Feb  7 23:31:25 Raspberry librespot[4317]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-mp8m.ap.spotify.com:4070"
Feb  7 23:31:26 Raspberry librespot[4317]: INFO:librespot_core::session: Authenticated as "user" !
Feb  7 23:31:26 Raspberry librespot[4317]: INFO:librespot::audio_backend::alsa: Using alsa sink
Feb  7 23:31:26 Raspberry librespot[4317]: INFO:librespot_core::session: Country: "HU"
Feb  7 23:31:37 Raspberry librespot[4317]: thread 'main' panicked at 'assertion failed: `(left == right)`
Feb  7 23:31:37 Raspberry librespot[4317]: left: `0`,
Feb  7 23:31:37 Raspberry librespot[4317]: right: `16`', core/src/util/spotify_id.rs:46:8
Feb  7 23:31:37 Raspberry librespot[4317]: note: Run with `RUST_BACKTRACE=1` for a backtrace.
Feb  7 23:31:37 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a
Feb  7 23:31:37 Raspberry systemd[1]: Unit raspotify.service entered failed state.
Feb  7 23:31:47 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart.
Feb  7 23:31:47 Raspberry systemd[1]: Stopping Raspotify...
Feb  7 23:31:47 Raspberry systemd[1]: Starting Raspotify...
Feb  7 23:31:47 Raspberry systemd[1]: Started Raspotify.
Feb  7 23:31:47 Raspberry librespot[4345]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY
Feb  7 23:31:47 Raspberry librespot[4345]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-jwtf.ap.spotify.com:4070"
Feb  7 23:31:48 Raspberry librespot[4345]: INFO:librespot_core::session: Authenticated as "user" !
Feb  7 23:31:48 Raspberry librespot[4345]: INFO:librespot::audio_backend::alsa: Using alsa sink
Feb  7 23:31:48 Raspberry librespot[4345]: INFO:librespot_core::session: Country: "HU"
Feb  7 23:32:01 Raspberry systemd[1]: Starting user-1000.slice.
Feb  7 23:32:01 Raspberry systemd[1]: Created slice user-1000.slice.
Feb  7 23:32:01 Raspberry systemd[1]: Starting User Manager for UID 1000...
Feb  7 23:32:01 Raspberry systemd[1]: Starting Session c1 of user pi.
Feb  7 23:32:01 Raspberry systemd[1]: Started Session c1 of user pi.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Paths.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Paths.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Timers.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Timers.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Paths.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Paths.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Timers.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Timers.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Sockets.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Sockets.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Basic System.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Basic System.
Feb  7 23:32:01 Raspberry systemd[4398]: Starting Default.
Feb  7 23:32:01 Raspberry systemd[4398]: Reached target Default.
Feb  7 23:32:01 Raspberry systemd[4398]: Startup finished in 36ms.
Feb  7 23:32:01 Raspberry systemd[1]: Started User Manager for UID 1000.
Feb  7 23:33:16 Raspberry librespot[4345]: thread 'main' panicked at 'assertion failed: `(left == right)`
Feb  7 23:33:16 Raspberry librespot[4345]: left: `0`,
Feb  7 23:33:16 Raspberry librespot[4345]: right: `16`', core/src/util/spotify_id.rs:46:8
Feb  7 23:33:16 Raspberry librespot[4345]: note: Run with `RUST_BACKTRACE=1` for a backtrace.
Feb  7 23:33:16 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a
Feb  7 23:33:16 Raspberry systemd[1]: Unit raspotify.service entered failed state.
Feb  7 23:33:26 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart.
Feb  7 23:33:26 Raspberry systemd[1]: Stopping Raspotify...
Feb  7 23:33:26 Raspberry systemd[1]: Starting Raspotify...
Feb  7 23:33:26 Raspberry systemd[1]: Started Raspotify.
Feb  7 23:33:26 Raspberry librespot[4519]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY
Feb  7 23:33:26 Raspberry librespot[4519]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-cqf9.ap.spotify.com:4070"
Feb  7 23:33:28 Raspberry librespot[4519]: INFO:librespot_core::session: Authenticated as "user" !
Feb  7 23:33:28 Raspberry librespot[4519]: INFO:librespot::audio_backend::alsa: Using alsa sink
Feb  7 23:33:28 Raspberry librespot[4519]: INFO:librespot_core::session: Country: "HU"
Feb  7 23:36:02 Raspberry librespot[4519]: thread 'main' panicked at 'assertion failed: `(left == right)`
Feb  7 23:36:02 Raspberry librespot[4519]: left: `0`,
Feb  7 23:36:02 Raspberry librespot[4519]: right: `16`', core/src/util/spotify_id.rs:46:8
Feb  7 23:36:02 Raspberry librespot[4519]: note: Run with `RUST_BACKTRACE=1` for a backtrace.
Feb  7 23:36:02 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a
Feb  7 23:36:02 Raspberry systemd[1]: Unit raspotify.service entered failed state.
Feb  7 23:36:12 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart.
Feb  7 23:36:12 Raspberry systemd[1]: Stopping Raspotify...
Feb  7 23:36:12 Raspberry systemd[1]: Starting Raspotify...
Feb  7 23:36:12 Raspberry systemd[1]: Started Raspotify.
Feb  7 23:36:12 Raspberry librespot[4684]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY
Feb  7 23:36:12 Raspberry librespot[4684]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-jws4.ap.spotify.com:4070"
Originally created by @matemoln on GitHub (Feb 7, 2018). Original GitHub issue: https://github.com/librespot-org/librespot/issues/126 Sometimes playback stucks and librespot restarts every time I try to play a track. The only way to stop it from restarting is to restart the iOS client. Here are the logs: ``` Feb 7 23:30:23 Raspberry librespot[1017]: INFO:librespot::player: Track "Downtown Beat" loaded Feb 7 23:30:32 Raspberry librespot[1017]: INFO:librespot::player: Loading track "Life Is Like - Original Mix" Feb 7 23:30:32 Raspberry librespot[1017]: INFO:librespot::player: Track "Life Is Like - Original Mix" loaded Feb 7 23:31:15 Raspberry librespot[1017]: thread 'main' panicked at 'assertion failed: `(left == right)` Feb 7 23:31:15 Raspberry librespot[1017]: left: `0`, Feb 7 23:31:15 Raspberry librespot[1017]: right: `16`', core/src/util/spotify_id.rs:46:8 Feb 7 23:31:15 Raspberry librespot[1017]: note: Run with `RUST_BACKTRACE=1` for a backtrace. Feb 7 23:31:15 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a Feb 7 23:31:15 Raspberry systemd[1]: Unit raspotify.service entered failed state. Feb 7 23:31:25 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart. Feb 7 23:31:25 Raspberry systemd[1]: Stopping Raspotify... Feb 7 23:31:25 Raspberry systemd[1]: Starting Raspotify... Feb 7 23:31:25 Raspberry systemd[1]: Started Raspotify. Feb 7 23:31:25 Raspberry librespot[4317]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY Feb 7 23:31:25 Raspberry librespot[4317]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-mp8m.ap.spotify.com:4070" Feb 7 23:31:26 Raspberry librespot[4317]: INFO:librespot_core::session: Authenticated as "user" ! Feb 7 23:31:26 Raspberry librespot[4317]: INFO:librespot::audio_backend::alsa: Using alsa sink Feb 7 23:31:26 Raspberry librespot[4317]: INFO:librespot_core::session: Country: "HU" Feb 7 23:31:37 Raspberry librespot[4317]: thread 'main' panicked at 'assertion failed: `(left == right)` Feb 7 23:31:37 Raspberry librespot[4317]: left: `0`, Feb 7 23:31:37 Raspberry librespot[4317]: right: `16`', core/src/util/spotify_id.rs:46:8 Feb 7 23:31:37 Raspberry librespot[4317]: note: Run with `RUST_BACKTRACE=1` for a backtrace. Feb 7 23:31:37 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a Feb 7 23:31:37 Raspberry systemd[1]: Unit raspotify.service entered failed state. Feb 7 23:31:47 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart. Feb 7 23:31:47 Raspberry systemd[1]: Stopping Raspotify... Feb 7 23:31:47 Raspberry systemd[1]: Starting Raspotify... Feb 7 23:31:47 Raspberry systemd[1]: Started Raspotify. Feb 7 23:31:47 Raspberry librespot[4345]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY Feb 7 23:31:47 Raspberry librespot[4345]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-jwtf.ap.spotify.com:4070" Feb 7 23:31:48 Raspberry librespot[4345]: INFO:librespot_core::session: Authenticated as "user" ! Feb 7 23:31:48 Raspberry librespot[4345]: INFO:librespot::audio_backend::alsa: Using alsa sink Feb 7 23:31:48 Raspberry librespot[4345]: INFO:librespot_core::session: Country: "HU" Feb 7 23:32:01 Raspberry systemd[1]: Starting user-1000.slice. Feb 7 23:32:01 Raspberry systemd[1]: Created slice user-1000.slice. Feb 7 23:32:01 Raspberry systemd[1]: Starting User Manager for UID 1000... Feb 7 23:32:01 Raspberry systemd[1]: Starting Session c1 of user pi. Feb 7 23:32:01 Raspberry systemd[1]: Started Session c1 of user pi. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Paths. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Paths. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Timers. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Timers. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Paths. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Paths. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Timers. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Timers. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Sockets. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Sockets. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Basic System. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Basic System. Feb 7 23:32:01 Raspberry systemd[4398]: Starting Default. Feb 7 23:32:01 Raspberry systemd[4398]: Reached target Default. Feb 7 23:32:01 Raspberry systemd[4398]: Startup finished in 36ms. Feb 7 23:32:01 Raspberry systemd[1]: Started User Manager for UID 1000. Feb 7 23:33:16 Raspberry librespot[4345]: thread 'main' panicked at 'assertion failed: `(left == right)` Feb 7 23:33:16 Raspberry librespot[4345]: left: `0`, Feb 7 23:33:16 Raspberry librespot[4345]: right: `16`', core/src/util/spotify_id.rs:46:8 Feb 7 23:33:16 Raspberry librespot[4345]: note: Run with `RUST_BACKTRACE=1` for a backtrace. Feb 7 23:33:16 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a Feb 7 23:33:16 Raspberry systemd[1]: Unit raspotify.service entered failed state. Feb 7 23:33:26 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart. Feb 7 23:33:26 Raspberry systemd[1]: Stopping Raspotify... Feb 7 23:33:26 Raspberry systemd[1]: Starting Raspotify... Feb 7 23:33:26 Raspberry systemd[1]: Started Raspotify. Feb 7 23:33:26 Raspberry librespot[4519]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY Feb 7 23:33:26 Raspberry librespot[4519]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-cqf9.ap.spotify.com:4070" Feb 7 23:33:28 Raspberry librespot[4519]: INFO:librespot_core::session: Authenticated as "user" ! Feb 7 23:33:28 Raspberry librespot[4519]: INFO:librespot::audio_backend::alsa: Using alsa sink Feb 7 23:33:28 Raspberry librespot[4519]: INFO:librespot_core::session: Country: "HU" Feb 7 23:36:02 Raspberry librespot[4519]: thread 'main' panicked at 'assertion failed: `(left == right)` Feb 7 23:36:02 Raspberry librespot[4519]: left: `0`, Feb 7 23:36:02 Raspberry librespot[4519]: right: `16`', core/src/util/spotify_id.rs:46:8 Feb 7 23:36:02 Raspberry librespot[4519]: note: Run with `RUST_BACKTRACE=1` for a backtrace. Feb 7 23:36:02 Raspberry systemd[1]: raspotify.service: main process exited, code=exited, status=101/n/a Feb 7 23:36:02 Raspberry systemd[1]: Unit raspotify.service entered failed state. Feb 7 23:36:12 Raspberry systemd[1]: raspotify.service holdoff time over, scheduling restart. Feb 7 23:36:12 Raspberry systemd[1]: Stopping Raspotify... Feb 7 23:36:12 Raspberry systemd[1]: Starting Raspotify... Feb 7 23:36:12 Raspberry systemd[1]: Started Raspotify. Feb 7 23:36:12 Raspberry librespot[4684]: INFO:librespot: librespot (raspotify) 932441d (2018-01-31). Built on 2018-02-03. Build ID: m5925LlY Feb 7 23:36:12 Raspberry librespot[4684]: INFO:librespot_core::session: Connecting to AP "gew1-accesspoint-b-jws4.ap.spotify.com:4070" ```
kerem 2026-02-27 19:28:49 +03:00
Author
Owner

@sashahilton00 commented on GitHub (Feb 8, 2018):

Hi, could you run it with export RUST_BACKTRACE=full? There's nothing in this particular log that is particularly useful.

<!-- gh-comment-id:363985399 --> @sashahilton00 commented on GitHub (Feb 8, 2018): Hi, could you run it with ```export RUST_BACKTRACE=full```? There's nothing in this particular log that is particularly useful.
Author
Owner

@matemoln commented on GitHub (Feb 15, 2018):

It haven't happened since.

<!-- gh-comment-id:365863949 --> @matemoln commented on GitHub (Feb 15, 2018): It haven't happened since.
Sign in to join this conversation.
No milestone
No project
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#99
No description provided.