-- Logs begin at Mon 2022-10-31 19:46:54 UTC, end at Sun 2024-10-06 16:04:05 UTC. -- Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : <2+2> (225645 ms) loaded Oct 06 16:03:01 tomsden volumio[853]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:01 tomsden volumio[853]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:01 tomsden volumio[853]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:01 tomsden volumio[853]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:01 tomsden volumio[853]: 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 06 16:03:01 tomsden volumio[853]: stack backtrace: Oct 06 16:03:02 tomsden volumio[853]: 0: 0xa38444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 06 16:03:02 tomsden volumio[853]: 1: 0xa38444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 06 16:03:02 tomsden volumio[853]: 2: 0xa38444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 06 16:03:02 tomsden volumio[853]: 3: 0xa38444 - ::fmt::h33b23b1d14f668ff Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 06 16:03:02 tomsden volumio[853]: 4: 0xa610dc - core::fmt::write::h6320fb0e6ac90e58 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 06 16:03:02 tomsden volumio[853]: 5: 0xa304c4 - std::io::Write::write_fmt::h33b25d4810ca481f Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 06 16:03:02 tomsden volumio[853]: 6: 0xa3aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 06 16:03:02 tomsden volumio[853]: 7: 0xa3aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 06 16:03:02 tomsden volumio[853]: 8: 0xa3aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 06 16:03:02 tomsden volumio[853]: 9: 0xa3ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 06 16:03:02 tomsden volumio[853]: 10: 0xa3b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 06 16:03:02 tomsden volumio[853]: 11: 0xa3b360 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:586:13 Oct 06 16:03:02 tomsden volumio[853]: 12: 0xa389c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 06 16:03:02 tomsden volumio[853]: 13: 0xa3b098 - rust_begin_unwind Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 06 16:03:02 tomsden volumio[853]: 14: 0x51e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 06 16:03:02 tomsden volumio[853]: 15: 0x51e5c8 - core::panicking::panic::h929d4344847bfdba Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:48:5 Oct 06 16:03:02 tomsden volumio[853]: 16: 0x659158 - ::write::h2857e81996e06a1e Oct 06 16:03:02 tomsden volumio[853]: 17: 0x63b814 - ::poll::hf6331a81451dbb28 Oct 06 16:03:02 tomsden volumio[853]: 18: 0x5636c0 - futures::task_impl::std::set::hb731921744f9247e Oct 06 16:03:02 tomsden volumio[853]: 19: 0x554708 - futures::task_impl::Spawn::poll_future_notify::h08f2c87ba9a4eead Oct 06 16:03:02 tomsden volumio[853]: 20: 0x568158 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb598e9d2970dbf13 Oct 06 16:03:02 tomsden volumio[853]: 21: 0x594e70 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7f140b0cfa5289e1 Oct 06 16:03:02 tomsden volumio[853]: 22: 0xa3febc - as core::ops::function::FnOnce>::call_once::hbc4d03a1815e028a Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9 Oct 06 16:03:02 tomsden volumio[853]: 23: 0xa3febc - as core::ops::function::FnOnce>::call_once::h6055603e421418e8 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9 Oct 06 16:03:02 tomsden volumio[853]: 24: 0xa3febc - std::sys::unix::thread::Thread::new::thread_start::h0518d9d0e6b0ba31 Oct 06 16:03:02 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys/unix/thread.rs:108:17 Oct 06 16:03:04 tomsden volumio[1082]: [SpotifyConnect] Setting VLS device_id: cb4574132a45f33655d96437df71b305bc224041 Oct 06 16:03:14 tomsden volumio[853]: 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 06 16:03:14 tomsden volumio[853]: stack backtrace: Oct 06 16:03:14 tomsden volumio[853]: 0: 0xa38444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 06 16:03:14 tomsden volumio[853]: 1: 0xa38444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 06 16:03:14 tomsden volumio[853]: 2: 0xa38444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 06 16:03:14 tomsden volumio[853]: 3: 0xa38444 - ::fmt::h33b23b1d14f668ff Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 06 16:03:14 tomsden volumio[853]: 4: 0xa610dc - core::fmt::write::h6320fb0e6ac90e58 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 06 16:03:14 tomsden volumio[853]: 5: 0xa304c4 - std::io::Write::write_fmt::h33b25d4810ca481f Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 06 16:03:14 tomsden volumio[853]: 6: 0xa3aef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 06 16:03:14 tomsden volumio[853]: 7: 0xa3aef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 06 16:03:14 tomsden volumio[853]: 8: 0xa3aef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 06 16:03:14 tomsden volumio[853]: 9: 0xa3ab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 06 16:03:14 tomsden volumio[853]: 10: 0xa3b694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 06 16:03:14 tomsden volumio[853]: 11: 0xa3b304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Oct 06 16:03:14 tomsden volumio[853]: 12: 0xa389c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 06 16:03:14 tomsden volumio[853]: 13: 0xa3b098 - rust_begin_unwind Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 06 16:03:14 tomsden volumio[853]: 14: 0x51e6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 06 16:03:14 tomsden volumio[853]: 15: 0x51e7c8 - core::result::unwrap_failed::h43118a132315b545 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/result.rs:1749:5 Oct 06 16:03:14 tomsden volumio[853]: 16: 0x638d9c - librespot_playback::player::Player::command::h858bc51b80bfd51a Oct 06 16:03:14 tomsden volumio[853]: 17: 0x638f40 - librespot_playback::player::Player::seek::h676084724b53451d Oct 06 16:03:14 tomsden volumio[853]: 18: 0x60c2c4 - librespot_connect::spirc::SpircTask::handle_seek::h6c67a21d167966eb Oct 06 16:03:14 tomsden volumio[853]: 19: 0x60af74 - ::poll::h2a7bff75029d3b82 Oct 06 16:03:14 tomsden volumio[853]: 20: 0x5462ec - ::poll::h5fd9e3bcdeb25ad9 Oct 06 16:03:14 tomsden volumio[853]: 21: 0x5633dc - futures::task_impl::std::set::h8f754e80bd562d0a Oct 06 16:03:14 tomsden volumio[853]: 22: 0x57a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Oct 06 16:03:14 tomsden volumio[853]: 23: 0x55f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Oct 06 16:03:14 tomsden volumio[853]: 24: 0x57a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Oct 06 16:03:14 tomsden volumio[853]: 25: 0x562880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Oct 06 16:03:14 tomsden volumio[853]: 26: 0x548eb0 - vollibrespot::main::h35555355ed947dd1 Oct 06 16:03:14 tomsden volumio[853]: 27: 0x568c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Oct 06 16:03:14 tomsden volumio[853]: 28: 0x58e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Oct 06 16:03:14 tomsden volumio[853]: 29: 0xa37b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 06 16:03:14 tomsden volumio[853]: 30: 0xa37b28 - std::panicking::try::do_call::hdf194554a41aa3cc Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 06 16:03:14 tomsden volumio[853]: 31: 0xa37b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 06 16:03:14 tomsden volumio[853]: 32: 0xa37b28 - std::panic::catch_unwind::h45f392a39f2fe48c Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 06 16:03:14 tomsden volumio[853]: 33: 0xa37b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 06 16:03:14 tomsden volumio[853]: 34: 0xa37b28 - std::panicking::try::do_call::hd126f715b519ff2a Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 06 16:03:14 tomsden volumio[853]: 35: 0xa37b28 - std::panicking::try::h80b7148ce71893dc Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 06 16:03:14 tomsden volumio[853]: 36: 0xa37b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 06 16:03:14 tomsden volumio[853]: 37: 0xa37b28 - std::rt::lang_start_internal::h3daa586a0b835507 Oct 06 16:03:14 tomsden volumio[853]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 06 16:03:14 tomsden volumio[853]: 38: 0x54ae80 - main Oct 06 16:03:14 tomsden volumio[853]: 39: 0xb6cb1718 - __libc_start_main Oct 06 16:03:14 tomsden volumio[853]: [Vollibrespot] : EventSender disconnected Oct 06 16:03:14 tomsden volumio[853]: [Vollibrespot] : Player thread panicked! Oct 06 16:03:14 tomsden volumio[1082]: [SpotifyConnect] Device palyback is inactive Oct 06 16:03:14 tomsden volumio[1082]: info: [1728230594806] ControllerSpotify::pushState Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:14 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:14 tomsden systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 06 16:03:14 tomsden systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 06 16:03:14 tomsden volumio[1082]: [SpotifyConnect] Sink released Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:14 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:03:14 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:14 tomsden volumio[1082]: info: CorePlayQueue::getTrack 0 Oct 06 16:03:14 tomsden volumio[1082]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Oct 06 16:03:16 tomsden systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Oct 06 16:03:16 tomsden systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 8. Oct 06 16:03:16 tomsden systemd[1]: Stopped Volspotconnect2 Daemon. Oct 06 16:03:16 tomsden systemd[1]: Started Volspotconnect2 Daemon. Oct 06 16:03:16 tomsden volumio[11545]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 06 16:03:16 tomsden volumio[11545]: Reading Config from "/tmp/volspotify.toml" Oct 06 16:03:16 tomsden volumio[11545]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 06 16:03:17 tomsden volumio[11545]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 06 16:03:34 tomsden volumio[11545]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Authenticated as "nitaovergaard" ! Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Using alsa sink Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Metadata pipe established Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Country: "CA" Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Fetching autoplay context uri Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Event: SessionActive { became_active_at: 1728230615758 } Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : SessionActive! Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 12223815182453989698835278997080079655, audio_type: Track } } Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 12223815182453989698835278997080079655, audio_type: Track } } Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] A connect session has begun Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] Device palyback is active! Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] Currently active: spop Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] Vollibrespot Active Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 06 16:03:35 tomsden volumio[1082]: [SpotifyConnect] Currently active: spop Oct 06 16:03:35 tomsden volumio[1082]: info: [1728230615760] ControllerSpotify::pushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:35 tomsden volumio[1082]: info: [1728230615763] ControllerSpotify::pushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:35 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:4J43elh6UdRXtM89al1iiY"> Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Resolving uri "spotify:station:artist:4J43elh6UdRXtM89al1iiY" Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Loading <2+2> with Spotify URI Oct 06 16:03:35 tomsden volumio[11545]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCdjObM05W5HQhjevZVIJa6SDWFGDkAQOJarOARDYQVmX0vO0-5jRDvT-dMNf1R2FOpsvnZD56ABcd1SobGAo36ird2KRcG2EVPORoIfdXT3NPc2hTow3DIh4bjD-DWIwvOB0P02wF8l2vAwv_zegrZM95C0LucfYClN654T70-JF25rhnOmuYJnSJaA1X3cKceat5ZL3ii8UPZZA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Oct 06 16:03:35 tomsden volumio[1082]: info: New Spotify Access Token Received Oct 06 16:03:35 tomsden volumio[1082]: info: Initializing Spotify Web API Oct 06 16:03:36 tomsden volumio[11545]: [Vollibrespot] : Resolved 50 tracks from <"spotify:artist:4J43elh6UdRXtM89al1iiY"> Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : <2+2> (225645 ms) loaded Oct 06 16:03:37 tomsden volumio[11545]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:37 tomsden volumio[11545]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:37 tomsden volumio[11545]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:144:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Oct 06 16:03:37 tomsden volumio[11545]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Oct 06 16:03:37 tomsden volumio[11545]: 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 06 16:03:37 tomsden volumio[11545]: stack backtrace: Oct 06 16:03:37 tomsden volumio[11545]: 0: 0x9e8444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 06 16:03:37 tomsden volumio[11545]: 1: 0x9e8444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 06 16:03:37 tomsden volumio[11545]: 2: 0x9e8444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 06 16:03:37 tomsden volumio[11545]: 3: 0x9e8444 - ::fmt::h33b23b1d14f668ff Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 06 16:03:37 tomsden volumio[11545]: 4: 0xa110dc - core::fmt::write::h6320fb0e6ac90e58 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 06 16:03:37 tomsden volumio[11545]: 5: 0x9e04c4 - std::io::Write::write_fmt::h33b25d4810ca481f Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 06 16:03:37 tomsden volumio[11545]: 6: 0x9eaef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 06 16:03:37 tomsden volumio[11545]: 7: 0x9eaef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 06 16:03:37 tomsden volumio[11545]: 8: 0x9eaef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 06 16:03:37 tomsden volumio[11545]: 9: 0x9eab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 06 16:03:37 tomsden volumio[11545]: 10: 0x9eb694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 06 16:03:37 tomsden volumio[11545]: 11: 0x9eb360 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:586:13 Oct 06 16:03:37 tomsden volumio[11545]: 12: 0x9e89c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 06 16:03:37 tomsden volumio[11545]: 13: 0x9eb098 - rust_begin_unwind Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 06 16:03:37 tomsden volumio[11545]: 14: 0x4ce6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 06 16:03:37 tomsden volumio[11545]: 15: 0x4ce5c8 - core::panicking::panic::h929d4344847bfdba Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:48:5 Oct 06 16:03:37 tomsden volumio[11545]: 16: 0x609158 - ::write::h2857e81996e06a1e Oct 06 16:03:37 tomsden volumio[11545]: 17: 0x5eb814 - ::poll::hf6331a81451dbb28 Oct 06 16:03:37 tomsden volumio[11545]: 18: 0x5136c0 - futures::task_impl::std::set::hb731921744f9247e Oct 06 16:03:37 tomsden volumio[11545]: 19: 0x504708 - futures::task_impl::Spawn::poll_future_notify::h08f2c87ba9a4eead Oct 06 16:03:37 tomsden volumio[11545]: 20: 0x518158 - std::sys_common::backtrace::__rust_begin_short_backtrace::hb598e9d2970dbf13 Oct 06 16:03:37 tomsden volumio[11545]: 21: 0x544e70 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h7f140b0cfa5289e1 Oct 06 16:03:37 tomsden volumio[11545]: 22: 0x9efebc - as core::ops::function::FnOnce>::call_once::hbc4d03a1815e028a Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9 Oct 06 16:03:37 tomsden volumio[11545]: 23: 0x9efebc - as core::ops::function::FnOnce>::call_once::h6055603e421418e8 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/alloc/src/boxed.rs:1853:9 Oct 06 16:03:37 tomsden volumio[11545]: 24: 0x9efebc - std::sys::unix::thread::Thread::new::thread_start::h0518d9d0e6b0ba31 Oct 06 16:03:37 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys/unix/thread.rs:108:17 Oct 06 16:03:43 tomsden volumio[11545]: 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 06 16:03:43 tomsden volumio[11545]: stack backtrace: Oct 06 16:03:43 tomsden volumio[11545]: 0: 0x9e8444 - std::backtrace_rs::backtrace::libunwind::trace::h40a4eff66330a36d Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Oct 06 16:03:43 tomsden volumio[11545]: 1: 0x9e8444 - std::backtrace_rs::backtrace::trace_unsynchronized::hbce01963451b0cd5 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Oct 06 16:03:43 tomsden volumio[11545]: 2: 0x9e8444 - std::sys_common::backtrace::_print_fmt::h95da650d09c7e00b Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:66:5 Oct 06 16:03:43 tomsden volumio[11545]: 3: 0x9e8444 - ::fmt::h33b23b1d14f668ff Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:45:22 Oct 06 16:03:43 tomsden volumio[11545]: 4: 0xa110dc - core::fmt::write::h6320fb0e6ac90e58 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/fmt/mod.rs:1190:17 Oct 06 16:03:43 tomsden volumio[11545]: 5: 0x9e04c4 - std::io::Write::write_fmt::h33b25d4810ca481f Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/io/mod.rs:1657:15 Oct 06 16:03:43 tomsden volumio[11545]: 6: 0x9eaef4 - std::sys_common::backtrace::_print::h7e61c5be69c715ef Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:48:5 Oct 06 16:03:43 tomsden volumio[11545]: 7: 0x9eaef4 - std::sys_common::backtrace::print::h3cee176d7c8dab8b Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:35:9 Oct 06 16:03:43 tomsden volumio[11545]: 8: 0x9eaef4 - std::panicking::default_hook::{{closure}}::h2d0211dd2e97dc43 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:295:22 Oct 06 16:03:43 tomsden volumio[11545]: 9: 0x9eab14 - std::panicking::default_hook::hcbdb3b7c40dca30c Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:314:9 Oct 06 16:03:43 tomsden volumio[11545]: 10: 0x9eb694 - std::panicking::rust_panic_with_hook::h4ce49e7a343dc12b Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:698:17 Oct 06 16:03:43 tomsden volumio[11545]: 11: 0x9eb304 - std::panicking::begin_panic_handler::{{closure}}::h4961100fae529fe9 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:588:13 Oct 06 16:03:43 tomsden volumio[11545]: 12: 0x9e89c4 - std::sys_common::backtrace::__rust_end_short_backtrace::hf2e68e62c7a80f1f Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/sys_common/backtrace.rs:138:18 Oct 06 16:03:43 tomsden volumio[11545]: 13: 0x9eb098 - rust_begin_unwind Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:584:5 Oct 06 16:03:43 tomsden volumio[11545]: 14: 0x4ce6f4 - core::panicking::panic_fmt::h84c42730e4a50809 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/panicking.rs:143:14 Oct 06 16:03:43 tomsden volumio[11545]: 15: 0x4ce7c8 - core::result::unwrap_failed::h43118a132315b545 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/result.rs:1749:5 Oct 06 16:03:43 tomsden volumio[11545]: 16: 0x5e8d9c - librespot_playback::player::Player::command::h858bc51b80bfd51a Oct 06 16:03:43 tomsden volumio[11545]: 17: 0x5e8f40 - librespot_playback::player::Player::seek::h676084724b53451d Oct 06 16:03:43 tomsden volumio[11545]: 18: 0x5bc2c4 - librespot_connect::spirc::SpircTask::handle_seek::h6c67a21d167966eb Oct 06 16:03:43 tomsden volumio[11545]: 19: 0x5baf74 - ::poll::h2a7bff75029d3b82 Oct 06 16:03:43 tomsden volumio[11545]: 20: 0x4f62ec - ::poll::h5fd9e3bcdeb25ad9 Oct 06 16:03:43 tomsden volumio[11545]: 21: 0x5133dc - futures::task_impl::std::set::h8f754e80bd562d0a Oct 06 16:03:43 tomsden volumio[11545]: 22: 0x52a42c - std::thread::local::LocalKey::with::h71cfa1e374fcc0a0 Oct 06 16:03:43 tomsden volumio[11545]: 23: 0x50f99c - tokio_current_thread::Entered

::block_on::hde55cdf28eabe6d5 Oct 06 16:03:43 tomsden volumio[11545]: 24: 0x52a6f0 - std::thread::local::LocalKey::with::hedba3cfe41326627 Oct 06 16:03:43 tomsden volumio[11545]: 25: 0x512880 - tokio::runtime::current_thread::runtime::Runtime::block_on::h60d5b89560100ed8 Oct 06 16:03:43 tomsden volumio[11545]: 26: 0x4f8eb0 - vollibrespot::main::h35555355ed947dd1 Oct 06 16:03:43 tomsden volumio[11545]: 27: 0x518c50 - std::sys_common::backtrace::__rust_begin_short_backtrace::hef626a10d324c12a Oct 06 16:03:43 tomsden volumio[11545]: 28: 0x53e52c - std::rt::lang_start::{{closure}}::hd80188e4bcac5072 Oct 06 16:03:43 tomsden volumio[11545]: 29: 0x9e7b28 - core::ops::function::impls:: for &F>::call_once::h0567da839a080232 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/core/src/ops/function.rs:259:13 Oct 06 16:03:43 tomsden volumio[11545]: 30: 0x9e7b28 - std::panicking::try::do_call::hdf194554a41aa3cc Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 06 16:03:43 tomsden volumio[11545]: 31: 0x9e7b28 - std::panicking::try::hbf4b7be9f9ffa5f8 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 06 16:03:43 tomsden volumio[11545]: 32: 0x9e7b28 - std::panic::catch_unwind::h45f392a39f2fe48c Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 06 16:03:43 tomsden volumio[11545]: 33: 0x9e7b28 - std::rt::lang_start_internal::{{closure}}::hd772cb03c2060671 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:48 Oct 06 16:03:43 tomsden volumio[11545]: 34: 0x9e7b28 - std::panicking::try::do_call::hd126f715b519ff2a Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:492:40 Oct 06 16:03:43 tomsden volumio[11545]: 35: 0x9e7b28 - std::panicking::try::h80b7148ce71893dc Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panicking.rs:456:19 Oct 06 16:03:43 tomsden volumio[11545]: 36: 0x9e7b28 - std::panic::catch_unwind::h1562f0b2e8a9cb0d Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/panic.rs:137:14 Oct 06 16:03:43 tomsden volumio[11545]: 37: 0x9e7b28 - std::rt::lang_start_internal::h3daa586a0b835507 Oct 06 16:03:43 tomsden volumio[11545]: at /rustc/7737e0b5c4103216d6fd8cf941b7ab9bdbaace7c/library/std/src/rt.rs:128:20 Oct 06 16:03:43 tomsden volumio[11545]: 38: 0x4fae80 - main Oct 06 16:03:43 tomsden volumio[11545]: 39: 0xb6c49718 - __libc_start_main Oct 06 16:03:43 tomsden volumio[11545]: [Vollibrespot] : Player thread panicked! Oct 06 16:03:43 tomsden volumio[11545]: [Vollibrespot] : EventSender disconnected Oct 06 16:03:43 tomsden volumio[1082]: [SpotifyConnect] Device palyback is inactive Oct 06 16:03:43 tomsden volumio[1082]: info: [1728230623009] ControllerSpotify::pushState Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:43 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:43 tomsden volumio[1082]: [SpotifyConnect] Sink released Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:03:43 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:03:43 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:03:43 tomsden systemd[1]: volspotconnect.service: Main process exited, code=exited, status=101/n/a Oct 06 16:03:43 tomsden volumio[1082]: info: CorePlayQueue::getTrack 0 Oct 06 16:03:43 tomsden volumio[1082]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received spop Oct 06 16:03:43 tomsden systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Oct 06 16:03:44 tomsden volumio[1082]: [SpotifyConnect] Setting VLS device_id: cb4574132a45f33655d96437df71b305bc224041 Oct 06 16:03:45 tomsden systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Oct 06 16:03:45 tomsden systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 9. Oct 06 16:03:45 tomsden systemd[1]: Stopped Volspotconnect2 Daemon. Oct 06 16:03:45 tomsden systemd[1]: Started Volspotconnect2 Daemon. Oct 06 16:03:45 tomsden volumio[17211]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Oct 06 16:03:45 tomsden volumio[17211]: Reading Config from "/tmp/volspotify.toml" Oct 06 16:03:45 tomsden volumio[17211]: [Vollibrespot] : Using Alsa backend with device: volumio Oct 06 16:03:45 tomsden volumio[17211]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Oct 06 16:03:58 tomsden volumio[17211]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Oct 06 16:04:01 tomsden vtcs[23733]: [2024-10-06 16:04:01] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 06 16:04:01 tomsden volumio[1082]: info: Pushing metadata Oct 06 16:04:01 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:04:01 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:04:01 tomsden volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 16:04:01 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:04:01 tomsden volumio[1082]: info: CoreCommandRouter::servicePushState Oct 06 16:04:01 tomsden volumio[1082]: info: CoreStateMachine::pushState Oct 06 16:04:01 tomsden volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Authenticated as "nitaovergaard" ! Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Using alsa sink Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Metadata pipe established Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Country: "CA" Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Event: Volume { volume_to_mixer: 65535 } Oct 06 16:04:04 tomsden volumio[1082]: [SpotifyConnect] Volume Spotify: 100 Volumio: 100 Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Fetching autoplay context uri Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Event: SessionActive { became_active_at: 1728230644921 } Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : SessionActive! Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 12223815182453989698835278997080079655, audio_type: Track } } Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 12223815182453989698835278997080079655, audio_type: Track } } Oct 06 16:04:04 tomsden volumio[1082]: [SpotifyConnect] A connect session has begun Oct 06 16:04:04 tomsden volumio[1082]: [SpotifyConnect] Device palyback is active! Oct 06 16:04:04 tomsden volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 06 16:04:04 tomsden volumio[1082]: [SpotifyConnect] Currently active: tidalconnect Oct 06 16:04:04 tomsden volumio[1082]: info: CoreCommandRouter::volumioStop Oct 06 16:04:04 tomsden volumio[1082]: info: CoreStateMachine::stop Oct 06 16:04:04 tomsden volumio[1082]: info: CoreStateMachine::serviceStop Oct 06 16:04:04 tomsden volumio[1082]: info: CoreCommandRouter::serviceStop Oct 06 16:04:04 tomsden volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 16:04:04 tomsden volumio[1082]: TypeError: Cannot read property 'then' of undefined Oct 06 16:04:04 tomsden volumio[1082]: at SpotConnEvents. (/data/plugins/music_service/spop/index.js:2034:27) Oct 06 16:04:04 tomsden volumio[1082]: at SpotConnEvents.emit (events.js:315:20) Oct 06 16:04:04 tomsden volumio[1082]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:73:16) Oct 06 16:04:04 tomsden volumio[1082]: at Socket. (/data/plugins/music_service/spop/SpotConnController.js:15:12) Oct 06 16:04:04 tomsden volumio[1082]: at Socket.emit (events.js:315:20) Oct 06 16:04:04 tomsden volumio[1082]: at UDP.onMessage [as onmessage] (dgram.js:919:8) Oct 06 16:04:04 tomsden volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 16:04:04 tomsden vtcs[23733]: [2024-10-06 16:04:04.952] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:4J43elh6UdRXtM89al1iiY"> Oct 06 16:04:04 tomsden volumio[17211]: [Vollibrespot] : Resolving uri "spotify:station:artist:4J43elh6UdRXtM89al1iiY" Oct 06 16:04:05 tomsden volumio[17211]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCLQOCqnhB0h8nFts1GJ2Tas1ElbSOwm3YWENkiU56NglZLsc6OrVs0UEpBqZGhSLC42WmXGpbjiZ9HOCDgSipuwuOLdp49qVbFLI-6vI47mA3ptfoyQ8ODw0aoTHJFVB0eiMPgKk78WClfDXn2b3FttNx6gTZ1hGB5mwcQlL6BZUsIyRGHVbKinYq71o4kKyUxHn8vOMBceGP_nw", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Oct 06 16:04:05 tomsden sudo[21264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 16:03 Oct 06 16:04:05 tomsden sudo[21264]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"