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