Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Spotifyd crash on track loading #1300

Open
klh opened this issue Sep 4, 2024 · 11 comments · May be fixed by #1301
Open

Spotifyd crash on track loading #1300

klh opened this issue Sep 4, 2024 · 11 comments · May be fixed by #1301
Labels
bug A functionality or parts of a program that do not work as intended

Comments

@klh
Copy link

klh commented Sep 4, 2024

heres a log when running the debug versions on ubuntu 24.04

./spotifyd --no-daemon
Loading config from "/etc/spotifyd.conf"
No username specified. Checking username_cmd
No username_cmd specified
No password specified. Checking password_cmd
No password_cmd specified
No proxy specified
Using software volume controller.
no usable credentials found, enabling discovery
Connecting to AP "ap2-gew4.spotify.com:443"
Authenticated as "114433150" !
Cannot save credentials to cache: Permission denied (os error 13)
Mixing with softvol and volume control: Log(60.0)
Country: "DK"
Using AlsaSink with format: S32
Cannot save volume to cache: Permission denied (os error 13)
Fetching autoplay context uri
Loading with Spotify URI spotify:track:4nnTUfKDl2XkwvHjZHmT6E
No autoplay_uri found
AutoplayError: MercuryError
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 191170311859469426423200040418154113560, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:3fHjx3ruK4a1OYPoKP80XR
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 142081239768991894398514149858183474223, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:610xMaOMK9GylOrNXNY86R
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 262756127457373075864999440008083417689, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:4ss7wUyzuLH26NvOBJjFuq
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 154851299808813996972192064036997059478, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:1wLGaZpnYpcFggvtmCPWUh
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 66757529502991446537255787334206295889, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:61gEaXyQAtYaBcJxyYdmc9
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 262939086924336323811252190799368775457, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:4ZwQ1QuIs43sekpZpsTeo6
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 39129898436559754098773695821424276475, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:1g7tqO5lzjQIQxdVZapBwf
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 55029952434040089990374237913439267675, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:2kRDxvlF2LyVkAx94UQPhT
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 102046387578937613867729659504398016833, audio_type: Track }>: ()
Loading with Spotify URI spotify:track:2Fsv6m5KIkPld2uWx7i6Kh
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 116553691544993384063498060646856169101, audio_type: Track }>: ()
No context to update from!
The application panicked (crashed).
Message: Map must not be polled after it returned Poll::Ready
Location: /home/kk/.cargo/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.28/src/future/future/map.rs:62

Backtrace omitted. Run with RUST_BACKTRACE=1 environment variable to display it.
Run with RUST_BACKTRACE=full to include source snippets.
The application panicked (crashed).
Message: called Option::unwrap() on a None value
Location: src/main_loop.rs:196

Backtrace omitted. Run with RUST_BACKTRACE=1 environment variable to display it.
Run with RUST_BACKTRACE=full to include source snippets.
Player thread Error: Any { .. }

@klh klh added the bug A functionality or parts of a program that do not work as intended label Sep 4, 2024
@klh
Copy link
Author

klh commented Sep 4, 2024

heres a --verbose output:

ound interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to [ff02::fb]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353 with no query
received packet from 192.168.0.181:5353
received packet from 192.168.0.181:5353 with no query
received packet from 192.168.0.103:5353
received packet from 192.168.0.103:5353 with no query
received packet from 192.168.0.191:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to 224.0.0.251:5353
received packet from [fe80::4ff:b777:dfa2:da62%2]:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to [ff02::fb]:5353
received packet from 192.168.0.123:5353
received packet from 192.168.0.123:5353 with no query
received packet from [fe80::5605:dbff:feab:adf4%2]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353 with no query
received packet from 192.168.0.181:5353
received packet from 192.168.0.181:5353 with no query
received packet from 192.168.0.139:5353
received question: IN _companion-link._tcp.local
received question: IN _hap._tcp.local
received question: IN _hap._udp.local
received question: IN _rdlink._tcp.local
received question: IN _sleep-proxy._udp.local
received packet from [fe80::1825:7c0b:b8a0:ad3d%2]:5353
received question: IN _companion-link._tcp.local
received question: IN _hap._tcp.local
received question: IN _hap._udp.local
received question: IN _rdlink._tcp.local
received question: IN _sleep-proxy._udp.local
received packet from [fd38:45dc:9764:5d49:7274:14ff:feb8:ee5b]:5353
received packet from [fd38:45dc:9764:5d49:7274:14ff:feb8:ee5b]:5353 with no query
received packet from 192.168.0.151:5353
received packet from 192.168.0.151:5353 with no query
received packet from 192.168.0.191:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to 224.0.0.251:5353
received packet from 192.168.0.123:5353
received packet from 192.168.0.123:5353 with no query
received packet from [fe80::4ff:b777:dfa2:da62%2]:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to [ff02::fb]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353 with no query
received packet from 192.168.0.181:5353
received packet from 192.168.0.181:5353 with no query
received packet from 192.168.0.103:5353
received packet from 192.168.0.103:5353 with no query
received packet from 192.168.0.139:5353
received question: IN _companion-link._tcp.local
received question: IN _hap._tcp.local
received question: IN _hap._udp.local
received question: IN _rdlink._tcp.local
received question: IN _sleep-proxy._udp.local
received packet from [fe80::1825:7c0b:b8a0:ad3d%2]:5353
received question: IN _companion-link._tcp.local
received question: IN _hap._tcp.local
received question: IN _hap._udp.local
received question: IN _rdlink._tcp.local
received question: IN _sleep-proxy._udp.local
received packet from 192.168.0.14:5353
received question: IN _netatmo-lcomm._tcp.local
received question: IN Netatmo-Presence-g7f5078.local
received packet from [fe80::72ee:50ff:fe7f:4bb2%2]:5353
received question: IN _netatmo-lcomm._tcp.local
received question: IN Netatmo-Presence-g7f5078.local
received packet from 192.168.0.191:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to 224.0.0.251:5353
received packet from [fe80::4ff:b777:dfa2:da62%2]:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to [ff02::fb]:5353
received packet from 192.168.0.123:5353
received packet from 192.168.0.123:5353 with no query
received packet from [fe80::5605:dbff:feab:adf4%2]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353 with no query
received packet from 192.168.0.191:5353
received question: IN lb._dns-sd._udp.local
received question: IN _airport._tcp.local
received question: IN _rdlink._tcp.local
received question: IN _hap._tcp.local
received question: IN _airplay._tcp.local
received question: IN _hap._udp.local
received question: IN _ssh._tcp.local
received question: IN _ftp._tcp.local
received question: IN _telnet._tcp.local
received question: IN _daap._tcp.local
received question: IN _touch-remote._tcp.local
received question: IN _uscan._tcp.local
received question: IN _printer._tcp.local
received question: IN _ptp._tcp.local
received question: IN _pdl-datastream._tcp.local
received question: IN _scanner._tcp.local
received question: IN _ipp._tcp.local
received question: IN _ippusb._tcp.local
received question: IN _ipps._tcp.local
received question: IN _uscans._tcp.local
received question: IN _afpovertcp._tcp.local
received question: IN _rfb._tcp.local
received question: IN _adisk._tcp.local
received question: IN _apple-mobdev._tcp.local
received question: IN 91b907fc._sub._apple-mobdev2._tcp.local
received question: IN _apple-pairable._tcp.local
received question: IN _companion-link._tcp.local
received question: IN _airdrop._tcp.local
received packet from 192.168.0.191:5353
received question: IN lb._dns-sd._udp.local
received question: IN _airport._tcp.local
received question: IN _rdlink._tcp.local
received question: IN _hap._tcp.local
received question: IN _airplay._tcp.local
received question: IN _hap._udp.local
received question: IN _ssh._tcp.local
received question: IN _ftp._tcp.local
received question: IN _telnet._tcp.local
received question: IN _daap._tcp.local
received question: IN _touch-remote._tcp.local
received question: IN _uscan._tcp.local
received question: IN _printer._tcp.local
received question: IN _ptp._tcp.local
received question: IN _pdl-datastream._tcp.local
received question: IN _scanner._tcp.local
received question: IN _ipp._tcp.local
received question: IN _ippusb._tcp.local
received question: IN _ipps._tcp.local
received question: IN _uscans._tcp.local
received question: IN _afpovertcp._tcp.local
received question: IN _rfb._tcp.local
received question: IN _adisk._tcp.local
received question: IN _apple-mobdev._tcp.local
received question: IN 91b907fc._sub._apple-mobdev2._tcp.local
received question: IN _apple-pairable._tcp.local
received question: IN _companion-link._tcp.local
received question: IN _airdrop._tcp.local
received packet from 192.168.0.14:5353
received packet from 192.168.0.14:5353 with no query
received packet from [fe80::72ee:50ff:fe7f:4bb2%2]:5353
received packet from [fe80::72ee:50ff:fe7f:4bb2%2]:5353 with no query
received packet from 192.168.0.14:5353
received packet from 192.168.0.14:5353 with no query
received packet from [fe80::72ee:50ff:fe7f:4bb2%2]:5353
received packet from [fe80::72ee:50ff:fe7f:4bb2%2]:5353 with no query
received packet from 192.168.0.151:5353
received packet from 192.168.0.151:5353 with no query
received packet from [fd38:45dc:9764:5d49:7274:14ff:feb8:ee5b]:5353
received packet from [fd38:45dc:9764:5d49:7274:14ff:feb8:ee5b]:5353 with no query
received packet from 192.168.0.98:5353
received packet from 192.168.0.98:5353 with no query
received packet from [fe80::fa0d:acff:fe79:ab08%2]:5353
received packet from [fe80::fa0d:acff:fe79:ab08%2]:5353 with no query
received packet from 192.168.0.192:5353
received packet from 192.168.0.192:5353 with no query
received packet from [fe80::4a2:8a46:aaee:c838%2]:5353
received packet from [fe80::4a2:8a46:aaee:c838%2]:5353 with no query
POST "/" {}
Connecting to AP "ap2-gew4.spotify.com:443"
Authenticated as "114433150" !
new Session[0]
Mixing with softvol and volume control: Log(60.0)
Normalisation Type: Auto
Normalisation Pregain: -5.0 dB
Normalisation Threshold: -2.0 dBFS
Normalisation Method: Dynamic
Normalisation Attack: 5 ms
Normalisation Release: 100 ms
Normalisation Knee: 5 dB
Session[0] strong=3 weak=1
Country: "DK"
new Spirc[0]
canonical_username: 114433150
new MercuryManager
Input volume 32767 mapped to: 3.16%
new Player[0]
Using AlsaSink with format: S32
command=AddEventSender
command=VolumeSet(32767)
received packet from 192.168.0.191:5353
received question: IN _spotify-connect._tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to 224.0.0.251:5353
received packet from [fe80::4ff:b777:dfa2:da62%2]:5353
received question: IN spotify-connect.tcp.local
found interface Interface { name: "enp2s0", addr: V4(Ifv4Addr { ip: 192.168.0.123, netmask: 255.255.255.0, broadcast: Some(192.168.0.255) }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:27f0:4394:456d:e7d5, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:fc90:9036:5416:1111, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
found interface Interface { name: "enp2s0", addr: V6(Ifv6Addr { ip: fd38:45dc:9764:5d49:5605:dbff:feab:adf4, netmask: ffff:ffff:ffff:ffff::, broadcast: None }) }
sending packet to [ff02::fb]:5353
received packet from 192.168.0.123:5353
received packet from 192.168.0.123:5353 with no query
received packet from [fe80::5605:dbff:feab:adf4%2]:5353
received packet from [fe80::5605:dbff:feab:adf4%2]:5353 with no query
unknown subscription uri=hm://remote/user/114433150/
subscribed uri=hm://remote/user/114433150/ count=0
received packet from 192.168.0.103:5353
received packet from 192.168.0.103:5353 with no query
received packet from 192.168.0.181:5353
received packet from 192.168.0.181:5353 with no query
kMessageTypeLoad "Klaus’s MacBook Pro" d9cde253dfcc35345334f93e42d5237459cb0d64 1048019035 1725477387485 kPlayStatusPlay
State: context_uri: "spotify:playlist:37i9dQZEVXcWoUr9XU54xI" index: 10 position_ms: 83 status: kPlayStatusPlay position_measured_at: 1725477387627 context_description: "" shuffle: true repeat: false playing_from_fallback: true row: 0 playing_track_index: 10 track {gid: "\n\344\235\235\013dKt\235\222\352]\344\005R\347"} track {gid: "\305\3209\342\020\336OU\203\272zu\201\253g!"} track {gid: "t\177F\210\253WL\236\203.\342\212^\206S\226"} track {gid: "\035p$\361\241CA\337\276\217\007\234\016\272\263\373"} track {gid: "q\222\231+\250\353O\233\266c\277\316\005\327\351i"} track {gid: "7G\353^\370\260@'\215\306\360\262\t\032D\027"} track {gid: "\241\242\366vVLO\006\253\240\t\225\366\354\231\205"} track {gid: "\332M\031\216^mM\267\237\231-\r&\227\3629"} track {gid: "\305\254\375H\357+Fh\204Jn\305\007\200*Y"} track {gid: ")fb!vQB\006\241\026,\004v
\353["} track {gid: "\240\334\327\263\022\335F\373\266\306\371|\341\340\226x"} track {gid: "L\305kZ\341\201JJ\230i#B\344m\241A"} track {gid: "z\001r:\022&D\340\260O\024]\364\241\016\206"} track {gid: "29\010Rv\373H*\202\220U\337\023\355\017Q"} track {gid: "W\257l\251\347\220E\036\235\204h\330
\022\226\215"}
Frame has 15 tracks
Fetching autoplay context uri
Sending status to server: [kPlayStatusPlay]
command=SetAutoNormaliseAsAlbum(false)
command=Load(SpotifyId { id: 213823159540208314897219512876648142456, audio_type: Track }, true, 83)
Sending status to server: [kPlayStatusPlay]
No autoplay_uri found
AutoplayError: MercuryError
Loading <Let's Take a Trip Together> with Spotify URI spotify:track:4TxHfNgeum23DHbz1dOZle
Downloading file 563e6fdab4ca94bc84ec9ba87c0db969f6f232fd
new ChannelManager
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 213823159540208314897219512876648142456, audio_type: Track }>: ()
At track 12 of 15 <"spotify:playlist:37i9dQZEVXcWoUr9XU54xI"> update [true]
Sending status to server: [kPlayStatusPlay]
command=Load(SpotifyId { id: 102046387578937613867729659504398016833, audio_type: Track }, true, 0)
Sending status to server: [kPlayStatusPlay]
Loading with Spotify URI spotify:track:2kRDxvlF2LyVkAx94UQPhT
Downloading file 39dfd6d60d03cce0e1275d0b5e9b0f02c6fa8248
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 102046387578937613867729659504398016833, audio_type: Track }>: ()
At track 13 of 15 <"spotify:playlist:37i9dQZEVXcWoUr9XU54xI"> update [true]
command=Load(SpotifyId { id: 162173324578163508063278372522830139014, audio_type: Track }, true, 0)
Sending status to server: [kPlayStatusPlay]
Sending status to server: [kPlayStatusPlay]
Loading <It's You> with Spotify URI spotify:track:3IdIW9NqNyz9ulLMHd8IBM
Downloading file 718b2c53d4333ac243e3126478092fae8726accd
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 162173324578163508063278372522830139014, audio_type: Track }>: ()
At track 14 of 15 <"spotify:playlist:37i9dQZEVXcWoUr9XU54xI"> update [true]
Sending status to server: [kPlayStatusPlay]
command=Load(SpotifyId { id: 66757529502991446537255787334206295889, audio_type: Track }, true, 0)
Sending status to server: [kPlayStatusPlay]
Loading with Spotify URI spotify:track:1wLGaZpnYpcFggvtmCPWUh
Downloading file de7b54728a438282adec7add1bf27cc12679948a
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 66757529502991446537255787334206295889, audio_type: Track }>: ()
At track 15 of 15 <"spotify:playlist:37i9dQZEVXcWoUr9XU54xI"> update [true]
Sending status to server: [kPlayStatusPlay]
command=Load(SpotifyId { id: 116553691544993384063498060646856169101, audio_type: Track }, true, 0)
Sending status to server: [kPlayStatusPlay]
Loading with Spotify URI spotify:track:2Fsv6m5KIkPld2uWx7i6Kh
Downloading file 7e13f4d9ab38b8e8289eb1b1173316ca6c89516b
channel error: 2 0
Unable to load encrypted file: ChannelError
Skipping to next track, unable to load track <SpotifyId { id: 116553691544993384063498060646856169101, audio_type: Track }>: ()
At track 16 of 15 <"spotify:playlist:37i9dQZEVXcWoUr9XU54xI"> update [true]
Extending playlist spotify:playlist:37i9dQZEVXcWoUr9XU54xI
No context to update from!
Sending status to server: [kPlayStatusPlay]
command=SetAutoNormaliseAsAlbum(false)
The application panicked (crashed).
Message: Map must not be polled after it returned Poll::Ready
Location: /home/kk/.cargo/registry/src/index.crates.io-6f17d22bba15001f/futures-util-0.3.28/src/future/future/map.rs:62

Backtrace omitted. Run with RUST_BACKTRACE=1 environment variable to display it.
Run with RUST_BACKTRACE=full to include source snippets.
drop PlayerInternal[0]
The application panicked (crashed).
Message: called Option::unwrap() on a None value
Location: src/main_loop.rs:196

Backtrace omitted. Run with RUST_BACKTRACE=1 environment variable to display it.
Run with RUST_BACKTRACE=full to include source snippets.
drop Spirc[0]
Shutting down player thread ...
Player thread Error: Any { .. }
drop Session[0]
drop ChannelManager
drop MercuryManager
drop Dispatch

@klh
Copy link
Author

klh commented Sep 4, 2024

digging further into this it seems like a few endpoint on Spotify's side are having problems.
librespot has blacklisted 2 of them in release 0.4.2

@klh
Copy link
Author

klh commented Sep 4, 2024

ok - recompiling latest using a locally built version of the librespot dev branch seems to work - librespot has fixed it in dev branch but it is not working in any of the releases.

@dennisfrijlink
Copy link

What does it mean for using Spotifyd? Is it possible to fix the problem by changing a version or edit something in the configuration?

@whisperzer0
Copy link

whisperzer0 commented Sep 4, 2024

For anyone having an issue with spotifyd skipping or crashing on any new tracks.
There is no fix for spotifyd itself as of yet, you will have to migrate to the DEV version of librespot -- and this requires installing rust and compiling the latest DEV release.

1: Install rust & cargo 

FreeBSD
# pkg install rust
Linux (as per https://doc.rust-lang.org/cargo/getting-started/installation.html)
# curl https://sh.rustup.rs -sSf | sh
2: Execute compile 
# cargo install --git https://github.com/librespot-org/librespot.git --branch dev --no-default-features --features pulseaudio-backend 

replace pulse-backend with alsa-backend if you don't use pulse in the command above

Other backends can be compiled and found here:
https://github.com/librespot-org/librespot/wiki/Compiling#addition-features

3: Copy ~/.cargo/bin/librespot to your executable dir as root

FreeBSD
# cp ~/.cargo/bin/librespot /usr/local/bin/
Linux
# cp ~/.cargo/bin/librespot /usr/bin/

assuming you previously setup Spotifyd

4: Execute: 
/path/to/librespot -C /path_to/spotifyd/cache/ -c /path_to/spotifyd/ -b 320 -n some_ident -f F32 &

Adapted from the instructions on:
librespot-org/librespot#1322 (comment)

@shadzik
Copy link

shadzik commented Sep 5, 2024

Had the same issue and had to install librespot 0.5.0dev on a Raspberry Pi 3 with Debian Bullseye.
Cargo is too old on this debian version, so you have to install rustup

export RUSTUP_HOME=/opt/rust/rustup
export CARGO_HOME=/opt/rust/cargo
curl https://sh.rustup.rs -sSf | sh -s -- --default-toolchain stable --profile default --no-modify-path -y

and then export your new PATH variable

export PATH="/opt/rust/cargo/bin:$PATH"

Then I could go and use

cargo build --release

To build the latest librespot, then it's like @whisperzer0 already mentioned, you can run librespot in client mode (no daemon mode AFAIK)

@lordievader
Copy link

For anyone having an issue with spotifyd skipping or crashing on any new tracks. There is no fix for spotifyd itself as of yet, you will have to migrate to the DEV version of librespot -- and this requires installing rust and compiling the latest DEV release.

1: Install rust & cargo 

FreeBSD
# pkg install rust
Linux (as per https://doc.rust-lang.org/cargo/getting-started/installation.html)
# curl https://sh.rustup.rs -sSf | sh
2: Execute compile 
# cargo install --git https://github.com/librespot-org/librespot.git --branch dev --no-default-features --features pulse-backend 

replace pulse-backend with alsa-backend if you don't use pulse in the command above

Other backends can be compiled and found here: https://github.com/librespot-org/librespot/wiki/Compiling#addition-features

3: Copy ~/.cargo/bin/librespot to your executable dir as root

FreeBSD
# cp ~/.cargo/bin/librespot /usr/local/bin/
Linux
# cp ~/.cargo/bin/librespot /usr/bin/

assuming you previously setup Spotifyd

4: Execute: 
/path/to/librespot -C /path_to/spotifyd/cache/ -c /path_to/spotifyd/ -b 320 -n some_ident -f F32 &

Adapted from the instructions on: librespot-org/librespot#1322 (comment)

In step two you want to specify the 'pulseaudio-backend' feature, otherwise you get an error. Following this method I got HTTP 403 errors with librespot.

What did fix things for me was pulling the sources and updating the librespot dependencies to version '0.4.2' instead of '0.4'. Rebuilding spotifyd allowed me to play music again.

diff --git a/Cargo.toml b/Cargo.toml
index 71a3cd0..8093137 100644
--- a/Cargo.toml
+++ b/Cargo.toml
@@ -28,11 +28,11 @@ structopt = "0.3.17"
 tokio = {version = "1.26.0", features = ["signal", "rt-multi-thread", "process", "io-std"] }
 tokio-stream = "0.1.7"
 url = "2.2.2"
-librespot-audio = { version = "0.4", default-features = false }
-librespot-playback = { version = "0.4", default-features = false }
-librespot-core = { version = "0.4" }
-librespot-discovery = { version = "0.4" }
-librespot-connect = { version = "0.4" }
+librespot-audio = { version = "0.4.2", default-features = false }
+librespot-playback = { version = "0.4.2", default-features = false }
+librespot-core = { version = "0.4.2" }
+librespot-discovery = { version = "0.4.2" }
+librespot-connect = { version = "0.4.2" }
 toml = "0.7"
 color-eyre = "0.6"
 directories = "5.0.1"

@lordievader lordievader linked a pull request Sep 5, 2024 that will close this issue
@whisperzer0
Copy link

In step two you want to specify the 'pulseaudio-backend' feature, otherwise you get an error. Following this method I got HTTP 403 errors with librespot.

mb, thanks for the heads-up. I've edited my post to rectified this.

@klh
Copy link
Author

klh commented Sep 7, 2024

Confirmation here,
Updating to 0.4.2 and bumping deps with cargo update fixes the issue

@artenverho
Copy link

Unfortunately not for long it seems… the issue is back for me.

@klh
Copy link
Author

klh commented Sep 8, 2024

Unfortunately not for long it seems… the issue is back for me.

Check that youre not running the old one by mistake - works perfectly here

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug A functionality or parts of a program that do not work as intended
Projects
None yet
Development

Successfully merging a pull request may close this issue.

6 participants