-- Logs begin at Thu 2024-05-09 02:07:30 UTC, end at Sat 2024-05-11 16:24:16 UTC. -- May 11 16:24:05 volumio volumio[1466]: thread 'main' panicked at 'range end index 16 out of range for slice of length 0', /build/cache/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/connect/src/discovery.rs:112:19 May 11 16:24:05 volumio volumio[1466]: stack backtrace: May 11 16:24:05 volumio volumio[1466]: 0: 0x9bd040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 11 16:24:05 volumio volumio[1466]: 1: 0x9bd040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 11 16:24:05 volumio volumio[1466]: 2: 0x9bd040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 11 16:24:05 volumio volumio[1466]: 3: 0x9bd040 - ::fmt::hc83ae421f857c6ae May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 11 16:24:05 volumio volumio[1466]: 4: 0x9de6a4 - core::fmt::write::h59f33d28fc1a9cf5 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 11 16:24:05 volumio volumio[1466]: 5: 0x9b5fec - std::io::Write::write_fmt::h96774110acd52d88 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 11 16:24:05 volumio volumio[1466]: 6: 0x9bf74c - std::sys_common::backtrace::_print::h28b9431057012c44 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 11 16:24:05 volumio volumio[1466]: 7: 0x9bf74c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 11 16:24:05 volumio volumio[1466]: 8: 0x9bf74c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 11 16:24:05 volumio volumio[1466]: 9: 0x9bf12c - std::panicking::default_hook::hee29440c29c1cc2e May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 11 16:24:05 volumio volumio[1466]: 10: 0x9bfdac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 11 16:24:05 volumio volumio[1466]: 11: 0x9bf928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 May 11 16:24:05 volumio volumio[1466]: 12: 0x9bd5bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 11 16:24:05 volumio volumio[1466]: 13: 0x9bf870 - rust_begin_unwind May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 11 16:24:05 volumio volumio[1466]: 14: 0x49921c - core::panicking::panic_fmt::h97b6949f75a63095 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 11 16:24:05 volumio volumio[1466]: 15: 0x499354 - core::slice::index::slice_end_index_len_fail::h8fce765fcb350d21 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/slice/index.rs:41:5 May 11 16:24:05 volumio volumio[1466]: 16: 0x53b4a0 - librespot_connect::discovery::Discovery::handle_add_user::h65788a04a5f834ea May 11 16:24:05 volumio volumio[1466]: 17: 0x56c328 - futures::future::chain::Chain::poll::he0cdc42634cb5c3c May 11 16:24:05 volumio volumio[1466]: 18: 0x558108 - as futures::future::Future>::poll::hadaf7a5b5d517c92 May 11 16:24:05 volumio volumio[1466]: 19: 0x53480c - hyper::proto::h1::dispatch::Dispatcher::poll_catch::h291ac0e7151ffddc May 11 16:24:05 volumio volumio[1466]: 20: 0x5969a4 - as futures::future::Future>::poll::he90da16eb57a6a4d May 11 16:24:05 volumio volumio[1466]: 21: 0x5a4908 - as futures::future::Future>::poll::hbc3a67223bcc8cbd May 11 16:24:05 volumio volumio[1466]: 22: 0x56b458 - futures::future::chain::Chain::poll::h3ef4e95ceb454569 May 11 16:24:05 volumio volumio[1466]: 23: 0x56bed8 - futures::future::chain::Chain::poll::h9f680812deeeced7 May 11 16:24:05 volumio volumio[1466]: 24: 0x4d9530 - futures::task_impl::std::set::h7682863852845195 May 11 16:24:05 volumio volumio[1466]: 25: 0x4ebc10 - tokio_current_thread::scheduler::Scheduler::tick::h2edcf115e36def28 May 11 16:24:05 volumio volumio[1466]: 26: 0x4cb790 - tokio_current_thread::Entered

::block_on::hac3e29a0a4da8120 May 11 16:24:05 volumio volumio[1466]: 27: 0x4f3178 - std::thread::local::LocalKey::with::h78cb87ff6f4c2b7d May 11 16:24:05 volumio volumio[1466]: 28: 0x508cd4 - tokio_timer::clock::clock::with_default::hb0853b95f60f0630 May 11 16:24:05 volumio volumio[1466]: 29: 0x4c59f4 - tokio::runtime::current_thread::runtime::Runtime::block_on::h239cb8d546240575 May 11 16:24:05 volumio volumio[1466]: 30: 0x4bf258 - vollibrespot::main::hf11cedd7d8a3be51 May 11 16:24:05 volumio volumio[1466]: 31: 0x4f2500 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha48b0377a890cb6d May 11 16:24:05 volumio volumio[1466]: 32: 0x4f2720 - std::rt::lang_start::{{closure}}::h120c4b14d2445a8e May 11 16:24:05 volumio volumio[1466]: 33: 0x9c0198 - core::ops::function::impls:: for &F>::call_once::hb7f0eab2b8615017 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 May 11 16:24:05 volumio volumio[1466]: 34: 0x9c0198 - std::panicking::try::do_call::h2c625414401a1a99 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 May 11 16:24:05 volumio volumio[1466]: 35: 0x9c0198 - std::panicking::try::h09167d835e285a07 May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 May 11 16:24:05 volumio volumio[1466]: 36: 0x9c0198 - std::panic::catch_unwind::hdff19ee37d8421eb May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 May 11 16:24:05 volumio volumio[1466]: 37: 0x9c0198 - std::rt::lang_start_internal::h835544f25e1ef31c May 11 16:24:05 volumio volumio[1466]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 May 11 16:24:05 volumio volumio[1466]: 38: 0x4c0a08 - main May 11 16:24:05 volumio volumio[1466]: 39: 0xb6bf6768 - __libc_start_main May 11 16:24:05 volumio systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a May 11 16:24:05 volumio systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. May 11 16:24:07 volumio systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. May 11 16:24:07 volumio systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 1. May 11 16:24:07 volumio systemd[1]: Stopped Volspotconnect2 Daemon. May 11 16:24:07 volumio systemd[1]: Started Volspotconnect2 Daemon. May 11 16:24:07 volumio volumio[4314]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 11 16:24:07 volumio volumio[4314]: Reading Config from "volspotify.toml" May 11 16:24:07 volumio volumio[4314]: [Vollibrespot] : Using Alsa backend with device: volumio May 11 16:24:07 volumio volumio[4314]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Authenticated as "andriusstankevicius" ! May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Using alsa sink May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Country: "LT" May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Metadata pipe established May 11 16:24:15 volumio volumio[4314]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } May 11 16:24:15 volumio volumio[937]: [SpotifyConnect] 32.99916075379568 May 11 16:24:15 volumio volumio[937]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 May 11 16:24:15 volumio volumio[937]: info: CoreStateMachine::pushState May 11 16:24:15 volumio volumio[937]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 16:24:15 volumio volumio[937]: info: CoreCommandRouter::volumioPushState May 11 16:24:15 volumio volumio[937]: info: MRS: Pushing multiroomSync output update for this device May 11 16:24:15 volumio volumio[937]: info: MRS: Pushing multiroomSync output May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Fetching autoplay context uri May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Event: SessionActive { became_active_at: 1715444656199 } May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : SessionActive! May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 319361188216272774507723993412049934202, audio_type: Track } } May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 319361188216272774507723993412049934202, audio_type: Track } } May 11 16:24:16 volumio volumio[937]: [SpotifyConnect] A connect session has begun May 11 16:24:16 volumio volumio[937]: [SpotifyConnect] Device palyback is active! May 11 16:24:16 volumio volumio[937]: info: CoreCommandRouter::volumioGetState May 11 16:24:16 volumio volumio[937]: [SpotifyConnect] Currently active: tidalconnect May 11 16:24:16 volumio volumio[937]: [SpotifyConnect] Stopping currently active service May 11 16:24:16 volumio volumio[937]: info: CoreCommandRouter::volumioStop May 11 16:24:16 volumio volumio[937]: info: CoreStateMachine::stop May 11 16:24:16 volumio volumio[937]: info: CoreStateMachine::serviceStop May 11 16:24:16 volumio volumio[937]: info: CoreCommandRouter::serviceStop May 11 16:24:16 volumio volumio[937]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 16:24:16 volumio vtcs[1491]: May 11 16:24:16.210 WARN Prepare playback after buffer underrun, module: camillalib::alsadevice May 11 16:24:16 volumio volumio[937]: TypeError: Cannot read property 'then' of undefined May 11 16:24:16 volumio volumio[937]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 11 16:24:16 volumio volumio[937]: at SpotConnEvents.emit (events.js:315:20) May 11 16:24:16 volumio volumio[937]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 11 16:24:16 volumio volumio[937]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 11 16:24:16 volumio volumio[937]: at Socket.emit (events.js:315:20) May 11 16:24:16 volumio volumio[937]: at UDP.onMessage [as onmessage] (dgram.js:919:8) May 11 16:24:16 volumio volumio[937]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:37i9dQZF1E39amHzw9Gi89"> May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Resolving uri "spotify:station:playlist:37i9dQZF1E39amHzw9Gi89" May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQChgav8VWw9v8Hra4Bsdk2O2UnSIlArpyXCWPFMud14rk3ps3HrMVqViBLquGw4XHaQ3_-EYEbHdC_PFmnRPR4Bk95VToU4WPy227i7mCGb-Jzm7aa7Aak4QhVsph_gaajn3FzqDAOeyfu0BfYLxlIfvmZRgIYaFQOiQJjPcRSYuwX0_UVy53m1Vm8Wk4tzn78Y_ass-S1i1lma8A", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 11 16:24:16 volumio volumio[4314]: [Vollibrespot] : Loading with Spotify URI May 11 16:24:16 volumio sudo[4336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-11 16:23 May 11 16:24:16 volumio sudo[4336]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"