-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-05-01 11:07:07 CEST. -- May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Authenticated as "hajdukveljko" ! May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Using alsa sink May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Country: "SE" May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Metadata pipe established May 01 11:07:05 volumio3 volumio[1192]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } May 01 11:07:05 volumio3 volumio[882]: [SpotifyConnect] 32.99916075379568 May 01 11:07:05 volumio3 volumio[882]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 May 01 11:07:05 volumio3 volumio[882]: info: CoreStateMachine::pushState May 01 11:07:05 volumio3 volumio[882]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 11:07:05 volumio3 volumio[882]: info: CoreCommandRouter::volumioPushState May 01 11:07:05 volumio3 volumio[882]: info: MRS: Pushing multiroomSync output update for this device May 01 11:07:05 volumio3 volumio[882]: info: MRS: Pushing multiroomSync output May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Fetching autoplay context uri May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714554426239 } May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : SessionActive! May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 102898000811418718874907607626690413487, audio_type: Track } } May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 102898000811418718874907607626690413487, audio_type: Track } } May 01 11:07:06 volumio3 volumio[882]: [SpotifyConnect] A connect session has begun May 01 11:07:06 volumio3 volumio[882]: [SpotifyConnect] Device palyback is active! May 01 11:07:06 volumio3 volumio[882]: info: CoreCommandRouter::volumioGetState May 01 11:07:06 volumio3 volumio[882]: [SpotifyConnect] Currently active: tidalconnect May 01 11:07:06 volumio3 volumio[882]: [SpotifyConnect] Stopping currently active service May 01 11:07:06 volumio3 volumio[882]: info: CoreCommandRouter::volumioStop May 01 11:07:06 volumio3 volumio[882]: info: CoreStateMachine::stop May 01 11:07:06 volumio3 volumio[882]: info: CoreStateMachine::serviceStop May 01 11:07:06 volumio3 volumio[882]: info: CoreCommandRouter::serviceStop May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:5bepW5vcdRzheNc0F8lHJ5"> May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Resolving uri "spotify:station:artist:5bepW5vcdRzheNc0F8lHJ5" May 01 11:07:06 volumio3 volumio[882]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 11:07:06 volumio3 volumio[882]: TypeError: Cannot read property 'then' of undefined May 01 11:07:06 volumio3 volumio[882]: at SpotConnEvents. (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 01 11:07:06 volumio3 volumio[882]: at SpotConnEvents.emit (events.js:315:20) May 01 11:07:06 volumio3 volumio[882]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 01 11:07:06 volumio3 volumio[882]: at Socket. (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 01 11:07:06 volumio3 volumio[882]: at Socket.emit (events.js:315:20) May 01 11:07:06 volumio3 volumio[882]: at UDP.onMessage [as onmessage] (dgram.js:919:8) May 01 11:07:06 volumio3 volumio[882]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 11:07:06 volumio3 vtcs[1213]: [2024-05-01 11:07:06] [info] asio async_shutdown error: asio.misc:2 (End of file) May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQC_X5dV8rlBBUyxYZg_Ggta99RVjiPw9VZ5F0ZuQpl_jP2LLbdo4gg1sfkPvzzS2xfgOins6rT02rdJACUX08BdMX0IJ9QHNqO-KVX4JvvOvkQIpRiLiOdfLIeWcyFLmnybwSNCEWZN9--iIG0dkmfe_37U5N5R3D7SdK6u2aHH9cv5KDGKEhgCkHyBjnR7beq9ZEn5", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Loading with Spotify URI May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Resolved 50 tracks from <"spotify:artist:5bepW5vcdRzheNc0F8lHJ5"> May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : (336706 ms) loaded May 01 11:07:06 volumio3 volumio[1192]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 01 11:07:06 volumio3 volumio[1192]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'ENODEV: No such device' May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 01 11:07:06 volumio3 volumio[1192]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 01 11:07:06 volumio3 volumio[1192]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'ENODEV: No such device' May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 01 11:07:06 volumio3 volumio[1192]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 01 11:07:06 volumio3 volumio[1192]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'ENODEV: No such device' May 01 11:07:06 volumio3 volumio[1192]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 01 11:07:06 volumio3 volumio[1192]: 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 May 01 11:07:06 volumio3 volumio[1192]: stack backtrace: May 01 11:07:07 volumio3 volumio[1192]: 0: 0xa5d040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 01 11:07:07 volumio3 volumio[1192]: 1: 0xa5d040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 01 11:07:07 volumio3 volumio[1192]: 2: 0xa5d040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 01 11:07:07 volumio3 volumio[1192]: 3: 0xa5d040 - ::fmt::hc83ae421f857c6ae May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 01 11:07:07 volumio3 volumio[1192]: 4: 0xa7e6a4 - core::fmt::write::h59f33d28fc1a9cf5 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 01 11:07:07 volumio3 volumio[1192]: 5: 0xa55fec - std::io::Write::write_fmt::h96774110acd52d88 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 01 11:07:07 volumio3 volumio[1192]: 6: 0xa5f74c - std::sys_common::backtrace::_print::h28b9431057012c44 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 01 11:07:07 volumio3 volumio[1192]: 7: 0xa5f74c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 01 11:07:07 volumio3 volumio[1192]: 8: 0xa5f74c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 01 11:07:07 volumio3 volumio[1192]: 9: 0xa5f12c - std::panicking::default_hook::hee29440c29c1cc2e May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 01 11:07:07 volumio3 volumio[1192]: 10: 0xa5fdac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 01 11:07:07 volumio3 volumio[1192]: 11: 0xa5f8f0 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:495:13 May 01 11:07:07 volumio3 volumio[1192]: 12: 0xa5d5bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 01 11:07:07 volumio3 volumio[1192]: 13: 0xa5f870 - rust_begin_unwind May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 01 11:07:07 volumio3 volumio[1192]: 14: 0x53921c - core::panicking::panic_fmt::h97b6949f75a63095 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 01 11:07:07 volumio3 volumio[1192]: 15: 0x539178 - core::panicking::panic::h1b7115dad5cfae19 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:50:5 May 01 11:07:07 volumio3 volumio[1192]: 16: 0x673bac - ::write::h2dc31352d1009910 May 01 11:07:07 volumio3 sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 11:06 May 01 11:07:07 volumio3 volumio[1192]: 17: 0x656588 - ::poll::h8080398afd4135f1 May 01 11:07:07 volumio3 sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 11:07:07 volumio3 volumio[1192]: 18: 0x5793ac - futures::task_impl::std::set::h5f18eafe66b6e0ce May 01 11:07:07 volumio3 volumio[1192]: 19: 0x565c88 - futures::task_impl::Spawn::poll_future_notify::hb5fbdb91a4afa239 May 01 11:07:07 volumio3 volumio[1192]: 20: 0x58aad0 - futures::future::Future::wait::h720e8c679b25c377 May 01 11:07:07 volumio3 volumio[1192]: 21: 0x592330 - std::sys_common::backtrace::__rust_begin_short_backtrace::h71e7578ce733f5f0 May 01 11:07:07 volumio3 volumio[1192]: 22: 0x585004 - core::ops::function::FnOnce::call_once{{vtable.shim}}::hf986f3b67bd2f3cd May 01 11:07:07 volumio3 volumio[1192]: 23: 0xa63430 - as core::ops::function::FnOnce>::call_once::h55699fe2dcd01fb2 May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9 May 01 11:07:07 volumio3 volumio[1192]: 24: 0xa63430 - as core::ops::function::FnOnce>::call_once::h767211a722faedbf May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/alloc/src/boxed.rs:1546:9 May 01 11:07:07 volumio3 volumio[1192]: 25: 0xa63430 - std::sys::unix::thread::Thread::new::thread_start::h4c3152afb00cbdcf May 01 11:07:07 volumio3 volumio[1192]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys/unix/thread.rs:71:17 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="b51559ff3f3719e35ff52cb9ccf2ff401b5ecb06" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="9f81dc1810002a5ad8861a42d98d57bc06a5d5d3" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 17 Apr 2024 07:54:00 PM CEST" VOLUMIO_VERSION="3.649" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="9c6dee1ade565aed57d77a70a260b988"