volumio/volumio-plugins-sources

Spotify: fails to connect with stacktrace in logs

Opened this issue · 0 comments

Volumio installed in a raspberry pi with a hifiberry DAC.
Spotify plugin version 3.0.9.

When connecting from desktop Spotify client, connection fails and the Volumio log shows the following:

[Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443"
[Vollibrespot] : Authenticated as "csaavedra" !
[Vollibrespot] : Using alsa sink
[Vollibrespot] : Metadata pipe established
[Vollibrespot] : Event: Volume { volume_to_mixer: 65535 }
[Vollibrespot] : Country: "FI"
[SpotifyConnect] Volume Spotify: 100 Volumio: 100
[Vollibrespot] : Fetching autoplay context uri
[Vollibrespot] : Event: SessionActive { became_active_at: 1688583959054 }
[Vollibrespot] : SessionActive!
[Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 277999119335806011727077590465924168756, audio_type: Track } }
[Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 277999119335806011727077590465924168756, audio_type: Track } }
[SpotifyConnect]  A connect session has begun
[SpotifyConnect]  Device palyback is active!
[SpotifyConnect]  Device palyback is loading
[Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:1H0ha8lbgTDJbvDbHbdbJp">
[Vollibrespot] : Resolving uri "spotify:station:playlist:1H0ha8lbgTDJbvDbHbdbJp"
[Vollibrespot] : error 403 for uri hm://keymaster/token/authenticated?client_id=4ced2e7c73484127be5d36015752eb43&scope=streaming,user-read-playback-state,user-modify-playback-state,user-read-currently-playing,user-read-private
[Vollibrespot] : Error: MercuryError
thread 'main' panicked at 'cannot poll Map twice', /build/cache/registry/src/github.com-1ecc6299db9ec823/futures-0.1.31/src/future/map.rs:35:29
stack backtrace:
   0:   0x9d8444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5
   1:   0x9d8444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5
   2:   0x9d8444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5
   3:   0x9d8444 - ::fmt::h33b23b1d14f668ff
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22
   4:   0xa010dc - core::fmt::write::h6320fb0e6ac90e58
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17
   5:   0x9d04c4 - std::io::Write::write_fmt::h33b25d4810ca481f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15
   6:   0x9daef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5
   7:   0x9daef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9
   8:   0x9daef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22
   9:   0x9dab14 - std::panicking::default_hook::hcbdb3b7c40dca30c
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9
  10:   0x9db694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17
  11:   0x9db304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13
  12:   0x9d89c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18
  13:   0x9db098 - rust_begin_unwind
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5
  14:   0x4be6f4 - core::panicking::panic_fmt::h84c42730e4a50809
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14
  15:   0x9fdf88 - core::panicking::panic_display::he50fbe261a13d083
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:73:5
  16:   0x9fdf38 - core::panicking::panic_str::h6e4cfb6a94cf14e8
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:56:5
  17:   0x4be57c - core::option::expect_failed::h5f2b901fbc810215
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/option.rs:1852:5
  18:   0x720e18 -  as futures::future::Future>::poll::h794cc547cc6bc145
  19:   0x5a9d78 - ::poll::h2a7bff75029d3b82
  20:   0x4e62ec - ::poll::h5fd9e3bcdeb25ad9
  21:   0x5033dc - futures::task_impl::std::set::h8f754e80bd562d0a
  22:   0x51a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0
  23:   0x4ff99c - tokio_current_thread::Entered
::block_on::hde55cdf28eabe6d5
  24:   0x51a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627
  25:   0x502880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8
  26:   0x4e8eb0 - vollibrespot::main::h35555355ed947dd1
  27:   0x508c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a
  28:   0x52e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072
  29:   0x9d7b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13
  30:   0x9d7b28 - std::panicking::try::do_call::hdf194554a41aa3cc
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
  31:   0x9d7b28 - std::panicking::try::hbf4b7be9f9ffa5f8
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
  32:   0x9d7b28 - std::panic::catch_unwind::h45f392a39f2fe48c
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
  33:   0x9d7b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48
  34:   0x9d7b28 - std::panicking::try::do_call::hd126f715b519ff2a
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40
  35:   0x9d7b28 - std::panicking::try::h80b7148ce71893dc
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19
  36:   0x9d7b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14
  37:   0x9d7b28 - std::rt::lang_start_internal::h3daa586a0b835507
                       at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20
  38:   0x4eae80 - main
  39: 0x76c52718 - __libc_start_main
[Vollibrespot] : EventSender disconnected
[SpotifyConnect]  PlaybackInactive