-- Logs begin at Wed 2024-04-24 20:50:20 UTC, end at Fri 2024-04-26 15:17:20 UTC. -- Apr 26 15:16:09 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:09 volumio volumio[830]: info: Listing playlists Apr 26 15:16:09 volumio volumio[830]: info: Listing playlists Apr 26 15:16:19 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:21 volumio volumio[31839]: [Vollibrespot] : couldn't parse packet from 192.168.1.142:5353: type 47 is invalid Apr 26 15:16:27 volumio sudo[32396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 15:16:27 volumio sudo[32396]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:16:27 volumio sudo[32398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 15:16:27 volumio sudo[32396]: pam_unix(sudo:session): session closed for user root Apr 26 15:16:27 volumio sudo[32398]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:16:27 volumio sudo[32398]: pam_unix(sudo:session): session closed for user root Apr 26 15:16:28 volumio volumio[31839]: thread '' panicked at 'Vorbis error: OggError(ReadError(Error { kind: UnexpectedEof, message: "failed to fill whole buffer" }))', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:771:56 Apr 26 15:16:28 volumio volumio[31839]: stack backtrace: Apr 26 15:16:28 volumio volumio[31839]: 0: 0x9a3c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 26 15:16:28 volumio volumio[31839]: 1: 0x9a3c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 26 15:16:28 volumio volumio[31839]: 2: 0x9a3c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Apr 26 15:16:28 volumio volumio[31839]: 3: 0x9a3c40 - ::fmt::hf1c91d83d30a63b5 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Apr 26 15:16:28 volumio volumio[31839]: 4: 0x9c9d68 - core::fmt::write::he7b81f119cac9e82 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Apr 26 15:16:28 volumio volumio[31839]: 5: 0x99d5d4 - std::io::Write::write_fmt::hd6a547671801193b Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Apr 26 15:16:28 volumio volumio[31839]: 6: 0x9a5728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Apr 26 15:16:28 volumio volumio[31839]: 7: 0x9a5728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Apr 26 15:16:28 volumio volumio[31839]: 8: 0x9a5728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Apr 26 15:16:28 volumio volumio[31839]: 9: 0x9a523c - std::panicking::default_hook::h3b931251a9c4b1f6 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Apr 26 15:16:28 volumio volumio[31839]: 10: 0x9a5f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Apr 26 15:16:28 volumio volumio[31839]: 11: 0x9a5c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Apr 26 15:16:28 volumio volumio[31839]: 12: 0x9a41bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Apr 26 15:16:28 volumio volumio[31839]: 13: 0x9a59ec - rust_begin_unwind Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Apr 26 15:16:28 volumio volumio[31839]: 14: 0x488e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Apr 26 15:16:28 volumio volumio[31839]: 15: 0x488f00 - core::result::unwrap_failed::ha8d0bf92d9b61b75 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/result.rs:1785:5 Apr 26 15:16:28 volumio volumio[31839]: 16: 0x5a08f0 - ::poll::h496db812513449d6 Apr 26 15:16:28 volumio volumio[31839]: 17: 0x4c8e50 - futures::task_impl::std::set::h5b276d7138d305a3 Apr 26 15:16:28 volumio volumio[31839]: 18: 0x4c8844 - futures::task_impl::std::ThreadNotify::with_current::h91e2a1e4a82a952d Apr 26 15:16:28 volumio volumio[31839]: 19: 0x4c8714 - futures::future::Future::wait::hd6e607993d6d71e9 Apr 26 15:16:28 volumio volumio[31839]: 20: 0x4d1a0c - std::sys_common::backtrace::__rust_begin_short_backtrace::hf0a3a4dca9c5761e Apr 26 15:16:28 volumio volumio[31839]: 21: 0x4ebd3c - core::ops::function::FnOnce::call_once{{vtable.shim}}::he2d346937b20b04f Apr 26 15:16:28 volumio volumio[31839]: 22: 0x9a8f8c - as core::ops::function::FnOnce>::call_once::h7a75751708f76c39 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/alloc/src/boxed.rs:1872:9 Apr 26 15:16:28 volumio volumio[31839]: 23: 0x9a8f8c - as core::ops::function::FnOnce>::call_once::ha623c3617a4a451b Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/alloc/src/boxed.rs:1872:9 Apr 26 15:16:28 volumio volumio[31839]: 24: 0x9a8f8c - std::sys::unix::thread::Thread::new::thread_start::h751d26e6bfaf6355 Apr 26 15:16:28 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys/unix/thread.rs:108:17 Apr 26 15:16:28 volumio sudo[32419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 15:16:28 volumio sudo[32419]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:16:28 volumio sudo[32421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 15:16:28 volumio sudo[32419]: pam_unix(sudo:session): session closed for user root Apr 26 15:16:28 volumio sudo[32421]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:16:28 volumio sudo[32421]: pam_unix(sudo:session): session closed for user root Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 15:16:28 volumio volumio[830]: info: Listing playlists Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 26 15:16:28 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 15:16:29 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:29 volumio volumio[830]: info: Listing playlists Apr 26 15:16:29 volumio volumio[830]: info: Listing playlists Apr 26 15:16:30 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 15:16:35 volumio volumio[830]: info: CoreCommandRouter::volumioNext Apr 26 15:16:35 volumio volumio[830]: info: CoreStateMachine::next Apr 26 15:16:35 volumio volumio[830]: info: Spotify next Apr 26 15:16:35 volumio volumio[31839]: thread 'main' panicked at 'called `Result::unwrap()` on an `Err` value: SendError("...")', /home/volumio/.cargo/git/checkouts/librespot-6f197fd632ef9380/08d8bcc/playback/src/player.rs:232:61 Apr 26 15:16:35 volumio volumio[31839]: stack backtrace: Apr 26 15:16:35 volumio volumio[31839]: 0: 0x9a3c40 - std::backtrace_rs::backtrace::libunwind::trace::h9be44abcd6023537 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/libunwind.rs:93:5 Apr 26 15:16:35 volumio volumio[31839]: 1: 0x9a3c40 - std::backtrace_rs::backtrace::trace_unsynchronized::he588770997e640ec Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 Apr 26 15:16:35 volumio volumio[31839]: 2: 0x9a3c40 - std::sys_common::backtrace::_print_fmt::h2a5bea2fa6ce9b3d Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:66:5 Apr 26 15:16:35 volumio volumio[31839]: 3: 0x9a3c40 - ::fmt::hf1c91d83d30a63b5 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:45:22 Apr 26 15:16:35 volumio volumio[31839]: 4: 0x9c9d68 - core::fmt::write::he7b81f119cac9e82 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/fmt/mod.rs:1196:17 Apr 26 15:16:35 volumio volumio[31839]: 5: 0x99d5d4 - std::io::Write::write_fmt::hd6a547671801193b Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/io/mod.rs:1654:15 Apr 26 15:16:35 volumio volumio[31839]: 6: 0x9a5728 - std::sys_common::backtrace::_print::hcf4a7819f0b4198f Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:48:5 Apr 26 15:16:35 volumio volumio[31839]: 7: 0x9a5728 - std::sys_common::backtrace::print::hb4deb0b578e6c939 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:35:9 Apr 26 15:16:35 volumio volumio[31839]: 8: 0x9a5728 - std::panicking::default_hook::{{closure}}::h7d65a85d73ca528f Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:295:22 Apr 26 15:16:35 volumio volumio[31839]: 9: 0x9a523c - std::panicking::default_hook::h3b931251a9c4b1f6 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:314:9 Apr 26 15:16:35 volumio volumio[31839]: 10: 0x9a5f44 - std::panicking::rust_panic_with_hook::h52cc4877d8c74a8b Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:698:17 Apr 26 15:16:35 volumio volumio[31839]: 11: 0x9a5c30 - std::panicking::begin_panic_handler::{{closure}}::h7546eed0ba906ec0 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:588:13 Apr 26 15:16:35 volumio volumio[31839]: 12: 0x9a41bc - std::sys_common::backtrace::__rust_end_short_backtrace::h32ad68afde8ac292 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/sys_common/backtrace.rs:138:18 Apr 26 15:16:35 volumio volumio[31839]: 13: 0x9a59ec - rust_begin_unwind Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:584:5 Apr 26 15:16:35 volumio volumio[31839]: 14: 0x488e2c - core::panicking::panic_fmt::h43e2baa6a2dcb05a Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/panicking.rs:142:14 Apr 26 15:16:35 volumio volumio[31839]: 15: 0x488f00 - core::result::unwrap_failed::ha8d0bf92d9b61b75 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/result.rs:1785:5 Apr 26 15:16:35 volumio volumio[31839]: 16: 0x59c628 - librespot_playback::player::Player::command::h6c7af72afc1a4f4e Apr 26 15:16:35 volumio volumio[31839]: 17: 0x59c700 - librespot_playback::player::Player::load::h9d87bbe659892aec Apr 26 15:16:35 volumio volumio[31839]: 18: 0x57d160 - librespot_connect::spirc::SpircTask::load_track::hb71d1408b2bfee60 Apr 26 15:16:35 volumio volumio[31839]: 19: 0x57ba3c - librespot_connect::spirc::SpircTask::handle_next::hb50ba27ba98bd6dd Apr 26 15:16:35 volumio volumio[31839]: 20: 0x5792b0 - ::poll::h980bb2790cc3c82d Apr 26 15:16:35 volumio volumio[31839]: 21: 0x4b2598 - ::poll::h06f390c1cf340695 Apr 26 15:16:35 volumio volumio[31839]: 22: 0x4c918c - futures::task_impl::std::set::h917345edbb2d7b17 Apr 26 15:16:35 volumio volumio[31839]: 23: 0x4eaf24 - std::thread::local::LocalKey::with::hebf033a3f9f19674 Apr 26 15:16:35 volumio volumio[31839]: 24: 0x4b9924 - tokio_current_thread::Entered

::block_on::h34935ca5acdeef78 Apr 26 15:16:35 volumio volumio[31839]: 25: 0x4eabac - std::thread::local::LocalKey::with::hb9ef5b7276039152 Apr 26 15:16:35 volumio volumio[31839]: 26: 0x4d9604 - tokio_reactor::with_default::hcd4b2b1df52c6b3d Apr 26 15:16:35 volumio volumio[31839]: 27: 0x4a8da4 - tokio::runtime::current_thread::runtime::Runtime::block_on::hdff564047631f32c Apr 26 15:16:35 volumio volumio[830]: [SpotifyConnect] Device palyback is inactive Apr 26 15:16:35 volumio volumio[830]: [SpotifyConnect] Sink released Apr 26 15:16:35 volumio volumio[830]: info: [1714144595792] ControllerSpotify::pushState Apr 26 15:16:35 volumio volumio[830]: info: CoreCommandRouter::servicePushState Apr 26 15:16:35 volumio volumio[830]: info: CoreStateMachine::pushState Apr 26 15:16:35 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 26 15:16:35 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 15:16:35 volumio volumio[31839]: 28: 0x4b39a0 - vollibrespot::main::haa33a046348d736f Apr 26 15:16:35 volumio volumio[31839]: 29: 0x4d16fc - std::sys_common::backtrace::__rust_begin_short_backtrace::h434d7d11e1d7d897 Apr 26 15:16:35 volumio volumio[31839]: 30: 0x4e9278 - std::rt::lang_start::{{closure}}::h7ee31a2c0e916c10 Apr 26 15:16:35 volumio volumio[31839]: 31: 0x996bcc - core::ops::function::impls:: for &F>::call_once::h9368c55e0af27eda Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/core/src/ops/function.rs:280:13 Apr 26 15:16:35 volumio volumio[31839]: 32: 0x996bcc - std::panicking::try::do_call::h7a5efa020212c0f3 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Apr 26 15:16:35 volumio volumio[31839]: 33: 0x996bcc - std::panicking::try::h3145f518af029892 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Apr 26 15:16:35 volumio volumio[31839]: 34: 0x996bcc - std::panic::catch_unwind::h876d78503e730edb Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Apr 26 15:16:35 volumio volumio[31839]: 35: 0x996bcc - std::rt::lang_start_internal::{{closure}}::hfb915b4bce05c29a Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:48 Apr 26 15:16:35 volumio volumio[31839]: 36: 0x996bcc - std::panicking::try::do_call::h6affa17582fe803e Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:492:40 Apr 26 15:16:35 volumio volumio[31839]: 37: 0x996bcc - std::panicking::try::hf3254f9f909f508b Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panicking.rs:456:19 Apr 26 15:16:35 volumio volumio[31839]: 38: 0x996bcc - std::panic::catch_unwind::h17abded8b6d92414 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/panic.rs:137:14 Apr 26 15:16:35 volumio volumio[31839]: 39: 0x996bcc - std::rt::lang_start_internal::h62925866154f59e6 Apr 26 15:16:35 volumio volumio[31839]: at /rustc/a8314ef7d0ec7b75c336af2c9857bfaf43002bfc/library/std/src/rt.rs:128:20 Apr 26 15:16:35 volumio volumio[31839]: 40: 0x4b411c - main Apr 26 15:16:35 volumio volumio[31839]: 41: 0x76bdd294 - __libc_start_main Apr 26 15:16:35 volumio volumio[31839]: [Vollibrespot] : Player thread panicked! Apr 26 15:16:35 volumio volumio[31839]: [Vollibrespot] : EventSender disconnected Apr 26 15:16:37 volumio systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 26 15:16:37 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 15:16:37 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 15:16:37 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 15:16:37 volumio volumio[32483]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 26 15:16:37 volumio volumio[32483]: Reading Config from "/tmp/volspotify.toml" Apr 26 15:16:37 volumio volumio[32483]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Apr 26 15:16:38 volumio volumio[32483]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 26 15:16:39 volumio volumio[830]: info: CoreCommandRouter::volumioNext Apr 26 15:16:39 volumio volumio[830]: info: CoreStateMachine::next Apr 26 15:16:39 volumio volumio[830]: info: Spotify next Apr 26 15:16:39 volumio volumio[830]: [SpotifyConnect] Not Found Apr 26 15:16:39 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:49 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:49 volumio volumio[830]: info: Listing playlists Apr 26 15:16:49 volumio volumio[830]: info: Listing playlists Apr 26 15:16:50 volumio volumio[32483]: [Vollibrespot] : couldn't parse packet from 192.168.1.142:5353: type 47 is invalid Apr 26 15:16:59 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:16:59 volumio volumio[32483]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Authenticated as "11173203487" ! Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Using alsa sink Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Metadata pipe established Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Country: "NL" Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Event: Volume { volume_to_mixer: 39672 } Apr 26 15:17:00 volumio volumio[830]: [SpotifyConnect] Volume Spotify: 60.53559166857404 Volumio: 61 Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714144620916 } Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : SessionActive! Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Fetching autoplay context uri Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:00 volumio volumio[830]: [SpotifyConnect] A connect session has begun Apr 26 15:17:00 volumio volumio[830]: [SpotifyConnect] Device palyback is active! Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQAd736u_R5M-_RCSH4LWlEK9XFUfMIeLvgCdqe9j4cfvjoLCXu4W6fzcKqdhM9MnPjcj_0pYKza6ZaCJa7vHaKHbd9c4T66PydbhF0tBlEVhTcJGMiWMgM0XEY4BMKd7Rh0nCzwZpLY_wX4Bc-RZxffXSXarzAbC01wM_iIJ1V2uR26f7jKdeeoyjGp0HQFb70UrjA", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 26 15:17:00 volumio volumio[830]: info: New Spotify Access Token Received Apr 26 15:17:00 volumio volumio[830]: info: Initializing Spotify Web API Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:track:0k7wmahjkn389wAZdz19Cv"> Apr 26 15:17:00 volumio volumio[32483]: [Vollibrespot] : Resolving uri "spotify:station:track:0k7wmahjkn389wAZdz19Cv" Apr 26 15:17:01 volumio volumio[32483]: [Vollibrespot] : Loading with Spotify URI Apr 26 15:17:01 volumio volumio[32483]: [Vollibrespot] : Resolved 50 tracks from <"spotify:track:0k7wmahjkn389wAZdz19Cv"> Apr 26 15:17:01 volumio volumio[32483]: [Vollibrespot] : (213520 ms) loaded Apr 26 15:17:01 volumio volumio[32483]: [Vollibrespot] : Event: SinkActive Apr 26 15:17:01 volumio volumio[830]: [SpotifyConnect] Sink acquired Apr 26 15:17:01 volumio volumio[830]: info: Continuing Spotify Session Apr 26 15:17:01 volumio volumio[830]: info: Checking Spotify Web API Apr 26 15:17:01 volumio volumio[830]: [SpotifyConnect] Vollibrespot Active Apr 26 15:17:01 volumio volumio[830]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 15:17:01 volumio volumio[830]: info: [1714144621734] ControllerSpotify::pushState Apr 26 15:17:01 volumio volumio[830]: info: CoreCommandRouter::servicePushState Apr 26 15:17:01 volumio volumio[830]: info: CoreStateMachine::pushState Apr 26 15:17:01 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 26 15:17:01 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 15:17:09 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:17:09 volumio volumio[830]: info: Listing playlists Apr 26 15:17:09 volumio volumio[830]: info: Listing playlists Apr 26 15:17:09 volumio volumio[32483]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track }, position_ms: 13018 } Apr 26 15:17:09 volumio volumio[830]: [SpotifyConnect] pause Apr 26 15:17:10 volumio volumio[830]: [SpotifyConnect] Drankin N Smokin Apr 26 15:17:10 volumio volumio[32483]: [Vollibrespot] : Event: SinkInactive Apr 26 15:17:10 volumio volumio[32483]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:10 volumio volumio[830]: [SpotifyConnect] Sink released Apr 26 15:17:10 volumio volumio[830]: info: [1714144630570] ControllerSpotify::pushState Apr 26 15:17:10 volumio volumio[830]: info: CoreCommandRouter::servicePushState Apr 26 15:17:10 volumio volumio[830]: info: CoreStateMachine::pushState Apr 26 15:17:10 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 26 15:17:10 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 15:17:10 volumio volumio[830]: [SpotifyConnect] Device palyback is inactive Apr 26 15:17:11 volumio volumio[830]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Apr 26 15:17:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 15:17:11 volumio volumio[830]: [SpotifyConnect] Closing metadata listener Apr 26 15:17:11 volumio volumio[830]: info: Disabling plugin spop Apr 26 15:17:11 volumio volumio[830]: info: Done. Apr 26 15:17:11 volumio sudo[32562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Apr 26 15:17:11 volumio sudo[32562]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:17:11 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 15:17:11 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Apr 26 15:17:11 volumio sudo[32562]: pam_unix(sudo:session): session closed for user root Apr 26 15:17:11 volumio volumio[830]: [SpotifyConnect] Vollibrespot Daemon service stoped! Apr 26 15:17:11 volumio volumio[830]: info: Is Not Connect Playback Apr 26 15:17:11 volumio volumio[830]: info: [1714144631434] ControllerSpotify::pushState Apr 26 15:17:11 volumio volumio[830]: info: CoreCommandRouter::servicePushState Apr 26 15:17:11 volumio volumio[830]: info: CoreStateMachine::pushState Apr 26 15:17:11 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 26 15:17:11 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 15:17:12 volumio volumio[830]: info: Enabling plugin volspotconnect2 Apr 26 15:17:12 volumio volumio[830]: info: Loading plugin "volspotconnect2"... Apr 26 15:17:12 volumio volumio[830]: info: PLUGIN START: volspotconnect2 Apr 26 15:17:12 volumio volumio[830]: [SpotifyConnect] Creating VLS config file Apr 26 15:17:12 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 15:17:12 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 15:17:12 volumio volumio[830]: [SpotifyConnect] Starting metadata listener Apr 26 15:17:12 volumio sudo[32574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Apr 26 15:17:12 volumio sudo[32574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:17:12 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 15:17:12 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 15:17:12 volumio sudo[32574]: pam_unix(sudo:session): session closed for user root Apr 26 15:17:12 volumio volumio[32579]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 26 15:17:12 volumio volumio[32579]: Reading Config from "volspotify.toml" Apr 26 15:17:12 volumio volumio[32579]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Apr 26 15:17:12 volumio volumio[830]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 26 15:17:12 volumio volumio[830]: [Metrics] SpotifyConnect: 0s 144.42ms Apr 26 15:17:12 volumio volumio[830]: info: Done. Apr 26 15:17:12 volumio volumio[32579]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 26 15:17:14 volumio volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 15:17:14 volumio volumio[830]: [SpotifyConnect] config : toggling initvol/volume_ctrl Apr 26 15:17:15 volumio volumio[830]: info: CALLMETHOD: music_service volspotconnect2 saveVolspotconnectAccount [object Object] Apr 26 15:17:15 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volspotconnect2 , saveVolspotconnectAccount Apr 26 15:17:15 volumio volumio[830]: [SpotifyConnect] Creating VLS config file Apr 26 15:17:15 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 15:17:15 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 15:17:15 volumio volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 26 15:17:15 volumio sudo[32608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Apr 26 15:17:15 volumio sudo[32608]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 15:17:15 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Apr 26 15:17:16 volumio systemd[1]: Starting Volspotconnect2 Daemon... Apr 26 15:17:16 volumio systemd[1]: Started Volspotconnect2 Daemon. Apr 26 15:17:16 volumio sudo[32608]: pam_unix(sudo:session): session closed for user root Apr 26 15:17:16 volumio volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 26 15:17:16 volumio volumio[32616]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Apr 26 15:17:16 volumio volumio[32616]: Reading Config from "volspotify.toml" Apr 26 15:17:16 volumio volumio[32616]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Apr 26 15:17:16 volumio volumio[32616]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Authenticated as "11173203487" ! Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:Headphone index:0 Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Alsa Mixer info min: -10239 (MilliBel(-9999999)[dB]) -- max: 400 (MilliBel(400)[dB]) HW: true Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Using alsa sink Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Metadata pipe established Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Country: "NL" Apr 26 15:17:18 volumio volumio[32616]: [Vollibrespot] : Event: Volume { volume_to_mixer: 0 } Apr 26 15:17:18 volumio volumio[830]: [SpotifyConnect] 0 Apr 26 15:17:18 volumio volumio[830]: [SpotifyConnect] Volume: Spotify:0 Volumio: 0 Apr 26 15:17:18 volumio volumio[830]: info: CoreStateMachine::pushState Apr 26 15:17:18 volumio volumio[830]: info: CoreCommandRouter::volumioPushState Apr 26 15:17:18 volumio volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : couldn't parse packet from V4(192.168.1.142:5353): type 47 is invalid Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Fetching autoplay context uri Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714144639491 } Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : SessionActive! Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:19 volumio volumio[830]: [SpotifyConnect] A connect session has begun Apr 26 15:17:19 volumio volumio[830]: [SpotifyConnect] Device palyback is active! Apr 26 15:17:19 volumio volumio[830]: info: CoreCommandRouter::volumioGetState Apr 26 15:17:19 volumio volumio[830]: [SpotifyConnect] Currently active: spop Apr 26 15:17:19 volumio volumio[830]: [SpotifyConnect] Stopping currently active service Apr 26 15:17:19 volumio volumio[830]: info: CoreCommandRouter::volumioStop Apr 26 15:17:19 volumio volumio[830]: info: CoreStateMachine::stop Apr 26 15:17:19 volumio volumio[830]: info: CoreStateMachine::serviceStop Apr 26 15:17:19 volumio volumio[830]: info: CoreCommandRouter::serviceStop Apr 26 15:17:19 volumio volumio[830]: error: WARNING: No stop method for service spop Apr 26 15:17:19 volumio volumio[830]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 15:17:19 volumio volumio[830]: TypeError: Cannot read property 'then' of undefined Apr 26 15:17:19 volumio volumio[830]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Apr 26 15:17:19 volumio volumio[830]: at emitNone (events.js:106:13) Apr 26 15:17:19 volumio volumio[830]: at SpotConnEvents.emit (events.js:208:7) Apr 26 15:17:19 volumio volumio[830]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Apr 26 15:17:19 volumio volumio[830]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Apr 26 15:17:19 volumio volumio[830]: at emitTwo (events.js:126:13) Apr 26 15:17:19 volumio volumio[830]: at Socket.emit (events.js:214:7) Apr 26 15:17:19 volumio volumio[830]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Apr 26 15:17:19 volumio volumio[830]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:track:0k7wmahjkn389wAZdz19Cv"> Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Resolving uri "spotify:station:track:0k7wmahjkn389wAZdz19Cv" Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQD3SO7KefTlaDO-xxKSWnVsWTvD8WfBjzo7QHKT7b3BpeWPb-GikjD3Ps44DkCUPo-nK0S2Jmb0HIlLdldNKbIE_fQsokx2BZSTF19EqTJGuFzCjalszxcXysPidGJmCTou_Visv8gZGbO6xpRKWcO-2Apy3BPakyjxph_Z4ko6XGR953FO4V-MozJKj58lXEvtC78", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Apr 26 15:17:19 volumio volumio[32616]: [Vollibrespot] : Loading with Spotify URI Apr 26 15:17:20 volumio volumio[32616]: [Vollibrespot] : Resolved 50 tracks from <"spotify:track:0k7wmahjkn389wAZdz19Cv"> Apr 26 15:17:20 volumio volumio[32616]: [Vollibrespot] : (213520 ms) loaded Apr 26 15:17:20 volumio volumio[32616]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 14173929789998118998926619280007852863, audio_type: Track } } Apr 26 15:17:20 volumio sudo[32642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 15:16 Apr 26 15:17:20 volumio sudo[32642]: 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="ceaca221623bd3cfdc448100963369088321c5bb" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"