Player got stuck in one song

[xd-mau5]

Describe the bug
After using spotify-player for like 15 minutes listening a playlist the player gets stuck on a song, even if you select previous or next, it stays on the same song, if you try to place another song by selecting it directly, it does not play the one you have chosen.

To Reproduce

1. start listening
2. wait at least 15 minutes
3. notice that one song got stuck

Expected behaviour
continue playing without issues

Log and backtrace
spotify-player-24-11-06-11-21.log

Screenshots
Video

Environment

• OS: Windows 11 (Pro) 23H2 22631.4391
• Application version: spotify_player 0.20.1

[dhgwilliam]

been happening to me to, i'm working on trying to predict it. one possibility on my mind is that when unpausing a song after a long idle, the player loses the context of the playlist? but i'm not 100% on that. the rest of the issue described above is the same for me, although i'm on a mac

Hardware Overview:

  Model Name: MacBook Pro
  Model Identifier: MacBookPro14,3
  Processor Name: Quad-Core Intel Core i7
  Processor Speed: 3.1 GHz
  Number of Processors: 1
  Total Number of Cores: 4
  L2 Cache (per Core): 256 KB
  L3 Cache: 8 MB
  Hyper-Threading Technology: Enabled
  Memory: 16 GB
  System Firmware Version: 529.140.2.0.0
  OS Loader Version: 577.140.2~30
  SMC Version (system): 2.45f5

      brew info spotify_player
==> spotify_player: stable 0.20.1 (bottled)
Command driven spotify player
https://github.com/aome510/spotify-player
Installed
/usr/local/Cellar/spotify_player/0.20.1 (8 files, 32.4MB) *
  Poured from bottle using the formulae.brew.sh API on 2024-11-11 at 19:02:22

[avastmick]

I get the same on MacOs - M2 MBP. Installed via cargo install spotify_player --features image.

After about 10 tracks it hangs on one track. If another is selected and played, it does so silently. Only a restart will recover.

Looking at the logs I get a failure on starting a new track until it loops through the playlist to get to the track it was playing previously and can play and starts again:

2025-01-06T07:00:49.552742Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([125, 99, 24, 109, 205, 177, 67, 90, 158, 58, 130, 59, 87, 104, 72, 232]), uri: None, queued: None, contex
       │ t: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [2] of 30
 603   │ 2025-01-06T07:00:49.552760Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([204, 75, 245, 84, 11, 181, 74, 25, 175, 197, 167, 225, 42, 94, 93, 118]), uri: None, queued: None, contex
       │ t: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [3] of 30
 604   │ 2025-01-06T07:00:49.552779Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([7, 96, 156, 167, 171, 64, 69, 197, 191, 54, 141, 198, 145, 81, 173, 184]), uri: None, queued: None, conte
       │ xt: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [4] of 30
 605   │ 2025-01-06T07:00:49.552798Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([103, 171, 177, 223, 188, 223, 76, 2, 162, 176, 150, 47, 112, 17, 13, 247]), uri: None, queued: None, cont
       │ ext: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [5] of 30
 606   │ 2025-01-06T07:00:49.552828Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([252, 183, 214, 23, 224, 149, 68, 47, 168, 236, 184, 27, 13, 66, 88, 186]), uri: None, queued: None, conte
       │ xt: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [6] of 30
 607   │ 2025-01-06T07:00:49.552848Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([53, 216, 17, 196, 66, 213, 76, 80, 157, 165, 240, 36, 25, 104, 145, 184]), uri: None, queued: None, conte
       │ xt: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [7] of 30
 608   │ 2025-01-06T07:00:49.552866Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([240, 226, 191, 135, 217, 151, 79, 192, 141, 153, 5, 112, 80, 111, 15, 169]), uri: None, queued: None, con
       │ text: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [8] of 30
 609   │ 2025-01-06T07:00:49.554594Z  INFO spotify_player::streaming: Got a new player event: Changed { playable_id: Track(TrackId("31CYUJj5f9lbQ0Qqm9PzK5")) }
 610   │ 2025-01-06T07:00:49.554661Z  INFO spotify_player::streaming: Got a new player event: Playing { playable_id: Track(TrackId("31CYUJj5f9lbQ0Qqm9PzK5")), position_ms: 0 }
 611   │ 2025-01-06T07:00:50.075805Z  INFO client_request{request=GetCurrentPlayback}: spotify_player::client: Successfully handled the client request, took: 419ms
 612   │ 2025-01-06T07:01:08.254304Z  INFO spotify_player::media_control: Got a media control event: Next
 613   │ 2025-01-06T07:01:08.480349Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([65, 148, 3, 216, 235, 165, 76, 114, 156, 223, 41, 78, 133, 67, 189, 196]), uri: None, queued: None, conte
       │ xt: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [10] of 30
 614   │ 2025-01-06T07:01:08.480521Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([43, 199, 91, 58, 27, 18, 74, 188, 134, 237, 5, 238, 199, 48, 20, 251]), uri: None, queued: None, context:
       │  Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [11] of 30
 615   │ 2025-01-06T07:01:08.480557Z  WARN librespot_connect::spirc: Skipping track <TrackRef { gid: Some([160, 18, 235, 79, 76, 204, 76, 94, 161, 230, 65, 232, 26, 116, 115, 203]), uri: None, queued: None, conte
       │ xt: Some("NonPlayable"), special_fields: SpecialFields { unknown_fields: UnknownFields { fields: None }, cached_size: CachedSize { size: 0 } } }> at position [12] of 30

[avastmick]

Update. I am looking at the logs (as per my earlier comment) and thinking about the issue. I have now tried the following:

• Playing a playlist of about 30 songs (~1 hour 45). Fails after about 10 tracks, as per the above errors
• Play the same playlist but also connect via phone Spotify App (watcher), and the whole playlist completes

Not ideal, but I can now play for longer than ~30 minutes. It seems as if it is an authorization error. It used to work fine, so maybe it's an upstream issue with librespot?