-- Logs begin at Mon 2025-10-13 08:26:20 UTC, end at Thu 2025-10-16 20:33:46 UTC. -- Oct 16 20:32:11 volumio volumio[1356]: info: CoreCommandRouter::servicePushState Oct 16 20:32:11 volumio volumio[1356]: info: CoreStateMachine::pushState Oct 16 20:32:11 volumio volumio[1356]: info: CoreCommandRouter::volumioPushState Oct 16 20:32:11 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:32:14 volumio vtcs[1718]: [2025-10-16 20:32:14] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 16 20:32:22 volumio volumio[9541]: [Vollibrespot] : Event: Seek { position_ms: 0 } Oct 16 20:32:22 volumio volumio[1356]: [SpotifyConnect] 0 Oct 16 20:32:22 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Oct 16 20:32:22 volumio volumio[1356]: [SpotifyConnect] Currently active: tidalconnect Oct 16 20:32:22 volumio volumio[1356]: [SpotifyConnect] Pushing new state :: false Oct 16 20:32:22 volumio volumio[1356]: info: CoreCommandRouter::servicePushState Oct 16 20:32:22 volumio volumio[1356]: info: CoreStateMachine::pushState Oct 16 20:32:22 volumio volumio[1356]: info: CoreCommandRouter::volumioPushState Oct 16 20:32:22 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:32:22 volumio volumio[9541]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 82950311837848652228794669338868779545, audio_type: Track } } Oct 16 20:32:22 volumio volumio[1356]: [SpotifyConnect] Device palyback is inactive Oct 16 20:32:22 volumio volumio[1356]: [SpotifyConnect] Device is paused Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 82950311837848652228794669338868779545, audio_type: Track }, position_ms: 0 } Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:32:23 volumio volumio[9541]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:32:23 volumio volumio[9541]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 Oct 16 20:32:23 volumio volumio[9541]: stack backtrace: Oct 16 20:32:23 volumio volumio[1356]: [SpotifyConnect] play Oct 16 20:32:24 volumio volumio[9541]: 0: 0xa7233c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Oct 16 20:32:24 volumio volumio[9541]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Oct 16 20:32:24 volumio volumio[9541]: 1: 0xa7233c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Oct 16 20:32:24 volumio volumio[9541]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Oct 16 20:32:24 volumio volumio[9541]: 2: 0xa7233c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:78 Oct 16 20:32:24 volumio volumio[9541]: 3: 0xa7233c - ::fmt::he1a5d6f378e506c4 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:59 Oct 16 20:32:24 volumio volumio[9541]: 4: 0xa92d5c - core::fmt::write::hb37ae5a5e0b70623 Oct 16 20:32:24 volumio volumio[9541]: at src/libcore/fmt/mod.rs:1076 Oct 16 20:32:24 volumio volumio[9541]: 5: 0xa6bc64 - std::io::Write::write_fmt::ha24bb3f5a858327b Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/io/mod.rs:1537 Oct 16 20:32:24 volumio volumio[9541]: 6: 0xa74974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:62 Oct 16 20:32:24 volumio volumio[9541]: 7: 0xa74974 - std::sys_common::backtrace::print::h2217cbc390250439 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:49 Oct 16 20:32:24 volumio volumio[9541]: 8: 0xa74974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/panicking.rs:198 Oct 16 20:32:24 volumio volumio[9541]: 9: 0xa74640 - std::panicking::default_hook::h46ab82039cbc65eb Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/panicking.rs:217 Oct 16 20:32:24 volumio volumio[9541]: 10: 0xa75054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/panicking.rs:526 Oct 16 20:32:24 volumio volumio[9541]: 11: 0xa74c54 - rust_begin_unwind Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/panicking.rs:437 Oct 16 20:32:24 volumio volumio[9541]: 12: 0xa90594 - core::panicking::panic_fmt::ha292e19d5ae716ed Oct 16 20:32:24 volumio volumio[9541]: at src/libcore/panicking.rs:85 Oct 16 20:32:24 volumio volumio[9541]: 13: 0xa904f0 - core::panicking::panic::heb457f4fa750842b Oct 16 20:32:24 volumio volumio[9541]: at src/libcore/panicking.rs:50 Oct 16 20:32:24 volumio volumio[9541]: 14: 0x66fee8 - ::write::h5da62cd8da1289c3 Oct 16 20:32:24 volumio volumio[9541]: 15: 0x6493c4 - ::poll::h2bafd5dc8d40bf3f Oct 16 20:32:24 volumio volumio[9541]: 16: 0x559eec - futures::task_impl::std::set::h2116cb0e9a5165fe Oct 16 20:32:24 volumio volumio[9541]: 17: 0x54ec6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Oct 16 20:32:24 volumio volumio[9541]: 18: 0x5686a0 - futures::future::Future::wait::ha8819dd7a48bc248 Oct 16 20:32:24 volumio volumio[9541]: 19: 0x5504b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Oct 16 20:32:24 volumio volumio[9541]: 20: 0x56f5a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Oct 16 20:32:24 volumio volumio[9541]: 21: 0xa7846c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Oct 16 20:32:24 volumio volumio[9541]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Oct 16 20:32:24 volumio volumio[9541]: 22: 0xa7846c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Oct 16 20:32:24 volumio volumio[9541]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Oct 16 20:32:24 volumio volumio[9541]: 23: 0xa7846c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Oct 16 20:32:24 volumio volumio[9541]: at src/libstd/sys/unix/thread.rs:87 Oct 16 20:32:24 volumio volumio[1356]: [SpotifyConnect] Us and Them Oct 16 20:32:24 volumio volumio[1356]: [SpotifyConnect] Not pushing metadata: { active: true, isStopping: true } Oct 16 20:33:21 volumio volumio[9541]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61 Oct 16 20:33:21 volumio volumio[9541]: stack backtrace: Oct 16 20:33:21 volumio volumio[9541]: 0: 0xa7233c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Oct 16 20:33:21 volumio volumio[9541]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Oct 16 20:33:21 volumio volumio[9541]: 1: 0xa7233c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Oct 16 20:33:21 volumio volumio[9541]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Oct 16 20:33:21 volumio volumio[9541]: 2: 0xa7233c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:78 Oct 16 20:33:21 volumio volumio[9541]: 3: 0xa7233c - ::fmt::he1a5d6f378e506c4 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:59 Oct 16 20:33:21 volumio volumio[9541]: 4: 0xa92d5c - core::fmt::write::hb37ae5a5e0b70623 Oct 16 20:33:21 volumio volumio[9541]: at src/libcore/fmt/mod.rs:1076 Oct 16 20:33:21 volumio volumio[9541]: 5: 0xa6bc64 - std::io::Write::write_fmt::ha24bb3f5a858327b Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/io/mod.rs:1537 Oct 16 20:33:21 volumio volumio[9541]: 6: 0xa74974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:62 Oct 16 20:33:21 volumio volumio[9541]: 7: 0xa74974 - std::sys_common::backtrace::print::h2217cbc390250439 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/sys_common/backtrace.rs:49 Oct 16 20:33:21 volumio volumio[9541]: 8: 0xa74974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:198 Oct 16 20:33:21 volumio volumio[9541]: 9: 0xa74640 - std::panicking::default_hook::h46ab82039cbc65eb Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:217 Oct 16 20:33:21 volumio volumio[9541]: 10: 0xa75054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:526 Oct 16 20:33:21 volumio volumio[9541]: 11: 0xa74c54 - rust_begin_unwind Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:437 Oct 16 20:33:21 volumio volumio[9541]: 12: 0xa90594 - core::panicking::panic_fmt::ha292e19d5ae716ed Oct 16 20:33:21 volumio volumio[9541]: at src/libcore/panicking.rs:85 Oct 16 20:33:21 volumio volumio[9541]: 13: 0xa903ec - core::option::expect_none_failed::h5afc8f3774fae01b Oct 16 20:33:21 volumio volumio[9541]: at src/libcore/option.rs:1269 Oct 16 20:33:21 volumio volumio[9541]: 14: 0x646390 - librespot_playback::player::Player::pause::hbb05b71e5eb46d2d Oct 16 20:33:21 volumio volumio[9541]: 15: 0x61df44 - librespot_connect::spirc::SpircTask::handle_pause::hf2fae95325d1811f Oct 16 20:33:21 volumio volumio[9541]: 16: 0x61bb6c - ::poll::ha2e91a6b75c719f3 Oct 16 20:33:21 volumio volumio[9541]: 17: 0x592ca4 - ::poll::hc5e7d2d1b7dbef13 Oct 16 20:33:21 volumio volumio[9541]: 18: 0x55a844 - futures::task_impl::std::set::h8f081cf0436110c9 Oct 16 20:33:21 volumio volumio[9541]: 19: 0x56e41c - std::thread::local::LocalKey::with::h711a13323aafc45a Oct 16 20:33:21 volumio volumio[9541]: 20: 0x54aa50 - tokio_current_thread::Entered

::block_on::h339073902a399eb7 Oct 16 20:33:21 volumio volumio[9541]: 21: 0x56ee00 - std::thread::local::LocalKey::with::hff64c13ccfec5327 Oct 16 20:33:21 volumio volumio[9541]: 22: 0x57b20c - tokio_reactor::with_default::h7ef12e65cb103d2f Oct 16 20:33:21 volumio volumio[9541]: 23: 0x54e9f8 - tokio::runtime::current_thread::runtime::Runtime::block_on::h043f136d6b8cf48f Oct 16 20:33:21 volumio volumio[9541]: 24: 0x5942c8 - vollibrespot::main::h02a0d49d4cc5eaa6 Oct 16 20:33:21 volumio volumio[9541]: 25: 0x57bf18 - std::rt::lang_start::{{closure}}::h279cd2b7929fdba7 Oct 16 20:33:21 volumio volumio[9541]: 26: 0xa75404 - std::rt::lang_start_internal::{{closure}}::he93bfc404849b78a Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/rt.rs:52 Oct 16 20:33:21 volumio volumio[9541]: 27: 0xa75404 - std::panicking::try::do_call::h6e9e98f4078affb0 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:348 Oct 16 20:33:21 volumio volumio[9541]: 28: 0xa75404 - std::panicking::try::h2e68d4f7f799a6df Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panicking.rs:325 Oct 16 20:33:21 volumio volumio[9541]: 29: 0xa75404 - std::panic::catch_unwind::h8880a4c07cc66391 Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/panic.rs:394 Oct 16 20:33:21 volumio volumio[9541]: 30: 0xa75404 - std::rt::lang_start_internal::hf4ae2140248bf16b Oct 16 20:33:21 volumio volumio[9541]: at src/libstd/rt.rs:51 Oct 16 20:33:21 volumio volumio[9541]: 31: 0x594d84 - main Oct 16 20:33:21 volumio volumio[9541]: 32: 0xb6ca9678 - __libc_start_main Oct 16 20:33:21 volumio volumio[9541]: [Vollibrespot] : Player thread panicked! Oct 16 20:33:21 volumio volumio[9541]: [Vollibrespot] : EventSender disconnected Oct 16 20:33:21 volumio systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a Oct 16 20:33:21 volumio systemd[1]: Unit volspotconnect2.service entered failed state. Oct 16 20:33:21 volumio volumio[1356]: [SpotifyConnect] Device palyback is inactive Oct 16 20:33:21 volumio volumio[1356]: [SpotifyConnect] Device Session is_active: true Oct 16 20:33:21 volumio volumio[1356]: [SpotifyConnect] Sink released Oct 16 20:33:21 volumio volumio[1356]: [SpotifyConnect] Not pushing Pause { active: true, isStopping: true} Oct 16 20:33:21 volumio volumio[1356]: [SpotifyConnect] ResolvedStop in 133s 572.57ms Oct 16 20:33:21 volumio volumio[1356]: info: TidalConnect Active Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Oct 16 20:33:21 volumio volumio[1356]: info: Currently active: volspotconnect2 Oct 16 20:33:21 volumio volumio[1356]: info: Setting Volatile state to tidalconnect Oct 16 20:33:21 volumio volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::servicePushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreStateMachine::pushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::volumioPushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::servicePushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreStateMachine::pushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::volumioPushState Oct 16 20:33:21 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:33:23 volumio systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 16 20:33:23 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Oct 16 20:33:23 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 16 20:33:23 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 16 20:33:23 volumio volumio[14995]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 16 20:33:23 volumio volumio[14995]: Reading Config from "volspotify.toml" Oct 16 20:33:23 volumio volumio[14995]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Oct 16 20:33:23 volumio volumio[14995]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Authenticated as "31xfmkir7hn56wow6tz5f3q4aoaa" ! Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Using alsa sink Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Metadata pipe established Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Oct 16 20:33:45 volumio volumio[1356]: [SpotifyConnect] 32.99916075379568 Oct 16 20:33:45 volumio volumio[1356]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Oct 16 20:33:45 volumio volumio[1356]: info: CoreStateMachine::pushState Oct 16 20:33:45 volumio volumio[1356]: info: CoreCommandRouter::volumioPushState Oct 16 20:33:45 volumio volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Country: "GB" Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Fetching autoplay context uri Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Event: SessionActive { became_active_at: 1760646825653 } Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : SessionActive! Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 82950311837848652228794669338868779545, audio_type: Track } } Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 82950311837848652228794669338868779545, audio_type: Track } } Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:0k17h0D3J5VfsdmQ1iZtE9"> Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Resolving uri "spotify:station:artist:0k17h0D3J5VfsdmQ1iZtE9" Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Loading with Spotify URI Oct 16 20:33:45 volumio volumio[14995]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQD6NiAslkZGxQozWS1NWpAF1acnBFRStaQXU-z4AlMVIMDdoi3D4MlMaF3HdOAGLrsRTW3AwqNbVKpvO1KKyny6VsR1PDJ-fY1fWIePngbA__BZEQA14jcv5d3BV95vZGYtpOZAHJU0D0CCYg_dmOAZKyRI0j1G2fTMTTSCRI-NpvMLgjKYwm0Ko4CxVe2WF8k_Ij2cqajfUY6-6U48SYnFi_2EdukJn8iXiISa3ybNz8dwVixmqQGL9DqGIAMKkd4P8sZjyg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Resolved 50 tracks from <"spotify:artist:0k17h0D3J5VfsdmQ1iZtE9"> Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : (469224 ms) loaded Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 16 20:33:46 volumio volumio[14995]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 16 20:33:46 volumio volumio[14995]: thread '' panicked at 'called `Option::unwrap()` on a `None` value', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/audio_backend/alsa.rs:129:35 Oct 16 20:33:46 volumio volumio[14995]: stack backtrace: Oct 16 20:33:46 volumio volumio[14995]: 0: 0x9f533c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Oct 16 20:33:46 volumio volumio[14995]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Oct 16 20:33:46 volumio volumio[14995]: 1: 0x9f533c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Oct 16 20:33:46 volumio volumio[14995]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Oct 16 20:33:46 volumio volumio[14995]: 2: 0x9f533c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/sys_common/backtrace.rs:78 Oct 16 20:33:46 volumio volumio[14995]: 3: 0x9f533c - ::fmt::he1a5d6f378e506c4 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/sys_common/backtrace.rs:59 Oct 16 20:33:46 volumio volumio[14995]: 4: 0xa15d5c - core::fmt::write::hb37ae5a5e0b70623 Oct 16 20:33:46 volumio volumio[14995]: at src/libcore/fmt/mod.rs:1076 Oct 16 20:33:46 volumio volumio[14995]: 5: 0x9eec64 - std::io::Write::write_fmt::ha24bb3f5a858327b Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/io/mod.rs:1537 Oct 16 20:33:46 volumio volumio[14995]: 6: 0x9f7974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/sys_common/backtrace.rs:62 Oct 16 20:33:46 volumio volumio[14995]: 7: 0x9f7974 - std::sys_common::backtrace::print::h2217cbc390250439 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/sys_common/backtrace.rs:49 Oct 16 20:33:46 volumio volumio[14995]: 8: 0x9f7974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/panicking.rs:198 Oct 16 20:33:46 volumio volumio[14995]: 9: 0x9f7640 - std::panicking::default_hook::h46ab82039cbc65eb Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/panicking.rs:217 Oct 16 20:33:46 volumio volumio[14995]: 10: 0x9f8054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/panicking.rs:526 Oct 16 20:33:46 volumio volumio[14995]: 11: 0x9f7c54 - rust_begin_unwind Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/panicking.rs:437 Oct 16 20:33:46 volumio volumio[14995]: 12: 0xa13594 - core::panicking::panic_fmt::ha292e19d5ae716ed Oct 16 20:33:46 volumio volumio[14995]: at src/libcore/panicking.rs:85 Oct 16 20:33:46 volumio volumio[14995]: 13: 0xa134f0 - core::panicking::panic::heb457f4fa750842b Oct 16 20:33:46 volumio volumio[14995]: at src/libcore/panicking.rs:50 Oct 16 20:33:46 volumio volumio[14995]: 14: 0x5f2ee8 - ::write::h5da62cd8da1289c3 Oct 16 20:33:46 volumio volumio[14995]: 15: 0x5cc3c4 - ::poll::h2bafd5dc8d40bf3f Oct 16 20:33:46 volumio volumio[14995]: 16: 0x4dceec - futures::task_impl::std::set::h2116cb0e9a5165fe Oct 16 20:33:46 volumio volumio[14995]: 17: 0x4d1c6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Oct 16 20:33:46 volumio volumio[14995]: 18: 0x4eb6a0 - futures::future::Future::wait::ha8819dd7a48bc248 Oct 16 20:33:46 volumio volumio[14995]: 19: 0x4d34b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Oct 16 20:33:46 volumio volumio[14995]: 20: 0x4f25a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Oct 16 20:33:46 volumio volumio[14995]: 21: 0x9fb46c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Oct 16 20:33:46 volumio volumio[14995]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Oct 16 20:33:46 volumio volumio[14995]: 22: 0x9fb46c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Oct 16 20:33:46 volumio volumio[14995]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Oct 16 20:33:46 volumio volumio[14995]: 23: 0x9fb46c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Oct 16 20:33:46 volumio volumio[14995]: at src/libstd/sys/unix/thread.rs:87 Oct 16 20:33:46 volumio volumio[1356]: [SpotifyConnect] A connect session has begun Oct 16 20:33:46 volumio volumio[1356]: [SpotifyConnect] Device palyback is active! Oct 16 20:33:46 volumio volumio[1356]: info: CoreCommandRouter::volumioGetState Oct 16 20:33:46 volumio volumio[1356]: [SpotifyConnect] Currently active: tidalconnect Oct 16 20:33:46 volumio volumio[1356]: [SpotifyConnect] Stopping currently active service Oct 16 20:33:46 volumio volumio[1356]: info: CoreCommandRouter::volumioStop Oct 16 20:33:46 volumio volumio[1356]: info: CoreStateMachine::stop Oct 16 20:33:46 volumio volumio[1356]: info: CoreStateMachine::serviceStop Oct 16 20:33:46 volumio volumio[1356]: info: CoreCommandRouter::serviceStop Oct 16 20:33:46 volumio volumio[1356]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:33:46 volumio volumio[1356]: TypeError: Cannot read property 'then' of undefined Oct 16 20:33:46 volumio volumio[1356]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Oct 16 20:33:46 volumio volumio[1356]: at emitNone (events.js:106:13) Oct 16 20:33:46 volumio volumio[1356]: at SpotConnEvents.emit (events.js:208:7) Oct 16 20:33:46 volumio volumio[1356]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Oct 16 20:33:46 volumio volumio[1356]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Oct 16 20:33:46 volumio volumio[1356]: at emitTwo (events.js:126:13) Oct 16 20:33:46 volumio volumio[1356]: at Socket.emit (events.js:214:7) Oct 16 20:33:46 volumio volumio[1356]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Oct 16 20:33:46 volumio volumio[1356]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 20:33:46 volumio vtcs[1718]: [2025-10-16 20:33:46.395] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Oct 16 20:33:46 volumio sudo[15058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 20:32 Oct 16 20:33:46 volumio sudo[15058]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"