-- 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"