-- Logs begin at Fri 2021-12-17 11:53:07 UTC, end at Sat 2026-02-07 10:22:14 UTC. -- Feb 07 10:21:06 volumio volumio[4426]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Feb 07 10:21:06 volumio volumio[4426]: [Vollibrespot] : Could not connect to server: early eof Feb 07 10:21:39 volumio volumio[4426]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Feb 07 10:21:39 volumio volumio[4426]: [Vollibrespot] : Could not connect to server: early eof Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Authenticated as "dam06d" ! Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Using alsa sink Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Country: "FR" Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Metadata pipe established Feb 07 10:22:12 volumio volumio[4426]: [Vollibrespot] : Event: Volume { volume_to_mixer: 39321 } Feb 07 10:22:12 volumio volumio[816]: [SpotifyConnect] Volume Spotify: 60 Volumio: 60 Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Fetching autoplay context uri Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Event: SessionActive { became_active_at: 1770459733111 } Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : SessionActive! Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 261134451073116636337443888592657944172, audio_type: Track } } Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 261134451073116636337443888592657944172, audio_type: Track } } Feb 07 10:22:13 volumio volumio[816]: [SpotifyConnect] A connect session has begun Feb 07 10:22:13 volumio volumio[816]: info: Acquiring new spotify session Feb 07 10:22:13 volumio volumio[816]: info: CoreCommandRouter::volumioStop Feb 07 10:22:13 volumio volumio[816]: info: CoreStateMachine::stop Feb 07 10:22:13 volumio volumio[816]: info: CoreStateMachine::serviceStop Feb 07 10:22:13 volumio volumio[816]: info: CoreCommandRouter::serviceStop Feb 07 10:22:13 volumio volumio[816]: error: WARNING: No stop method for service airplay Feb 07 10:22:13 volumio volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:artist:7w9jdhcgHNdiPeNPUoFSlx"> Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Resolving uri "spotify:station:artist:7w9jdhcgHNdiPeNPUoFSlx" Feb 07 10:22:13 volumio volumio[816]: TypeError: Cannot read property 'then' of undefined Feb 07 10:22:13 volumio volumio[816]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/spop/index.js:2095:31) Feb 07 10:22:13 volumio volumio[816]: at emitNone (events.js:106:13) Feb 07 10:22:13 volumio volumio[816]: at SpotConnEvents.emit (events.js:208:7) Feb 07 10:22:13 volumio volumio[816]: at SpotConnEvents.parseData (/data/plugins/music_service/spop/SpotConnController.js:81:16) Feb 07 10:22:13 volumio volumio[816]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/spop/SpotConnController.js:15:12) Feb 07 10:22:13 volumio volumio[816]: at emitTwo (events.js:126:13) Feb 07 10:22:13 volumio volumio[816]: at Socket.emit (events.js:214:7) Feb 07 10:22:13 volumio volumio[816]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Feb 07 10:22:13 volumio volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Loading with Spotify URI Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQBvSqTDyV-EThKOnz7iVxSSD3_HX7M9kbP20Ahtqvlah8SPIh2LEiHkBe7xYLM1Vkt7WcOHlEFc4QfeuVOPetQgyfByGc8XQngh6i5YyfrgKEv05XAuZ2MTmqjcAi34Q0REVdZbqcNw4cjcY3XHW9en8x9C4k5QuHKtDhGEOFmBevYepQD2NtJa2Bcezi43BQqovFlxnIEUmKrbPsCr0URNMA_QIoBCQRMvFIS7I0Ku", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-modify-playback-state", "user-read-playback-state", "user-read-currently-playing", "user-read-private"] } } Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Resolved 50 tracks from <"spotify:artist:7w9jdhcgHNdiPeNPUoFSlx"> Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : (109134 ms) loaded Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Feb 07 10:22:13 volumio volumio[4426]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Feb 07 10:22:13 volumio volumio[4426]: 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 Feb 07 10:22:13 volumio volumio[4426]: stack backtrace: Feb 07 10:22:13 volumio volumio[4426]: 0: 0xa0233c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Feb 07 10:22:13 volumio volumio[4426]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Feb 07 10:22:13 volumio volumio[4426]: 1: 0xa0233c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Feb 07 10:22:13 volumio volumio[4426]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Feb 07 10:22:13 volumio volumio[4426]: 2: 0xa0233c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/sys_common/backtrace.rs:78 Feb 07 10:22:13 volumio volumio[4426]: 3: 0xa0233c - ::fmt::he1a5d6f378e506c4 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/sys_common/backtrace.rs:59 Feb 07 10:22:13 volumio volumio[4426]: 4: 0xa22d5c - core::fmt::write::hb37ae5a5e0b70623 Feb 07 10:22:13 volumio volumio[4426]: at src/libcore/fmt/mod.rs:1076 Feb 07 10:22:13 volumio volumio[4426]: 5: 0x9fbc64 - std::io::Write::write_fmt::ha24bb3f5a858327b Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/io/mod.rs:1537 Feb 07 10:22:13 volumio volumio[4426]: 6: 0xa04974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/sys_common/backtrace.rs:62 Feb 07 10:22:13 volumio volumio[4426]: 7: 0xa04974 - std::sys_common::backtrace::print::h2217cbc390250439 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/sys_common/backtrace.rs:49 Feb 07 10:22:13 volumio volumio[4426]: 8: 0xa04974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/panicking.rs:198 Feb 07 10:22:13 volumio volumio[4426]: 9: 0xa04640 - std::panicking::default_hook::h46ab82039cbc65eb Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/panicking.rs:217 Feb 07 10:22:13 volumio volumio[4426]: 10: 0xa05054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/panicking.rs:526 Feb 07 10:22:13 volumio volumio[4426]: 11: 0xa04c54 - rust_begin_unwind Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/panicking.rs:437 Feb 07 10:22:13 volumio volumio[4426]: 12: 0xa20594 - core::panicking::panic_fmt::ha292e19d5ae716ed Feb 07 10:22:13 volumio volumio[4426]: at src/libcore/panicking.rs:85 Feb 07 10:22:13 volumio volumio[4426]: 13: 0xa204f0 - core::panicking::panic::heb457f4fa750842b Feb 07 10:22:13 volumio volumio[4426]: at src/libcore/panicking.rs:50 Feb 07 10:22:13 volumio volumio[4426]: 14: 0x5ffee8 - ::write::h5da62cd8da1289c3 Feb 07 10:22:13 volumio volumio[4426]: 15: 0x5d93c4 - ::poll::h2bafd5dc8d40bf3f Feb 07 10:22:13 volumio volumio[4426]: 16: 0x4e9eec - futures::task_impl::std::set::h2116cb0e9a5165fe Feb 07 10:22:13 volumio volumio[4426]: 17: 0x4dec6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Feb 07 10:22:13 volumio volumio[4426]: 18: 0x4f86a0 - futures::future::Future::wait::ha8819dd7a48bc248 Feb 07 10:22:13 volumio volumio[4426]: 19: 0x4e04b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Feb 07 10:22:13 volumio volumio[4426]: 20: 0x4ff5a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Feb 07 10:22:13 volumio volumio[4426]: 21: 0xa0846c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Feb 07 10:22:13 volumio volumio[4426]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Feb 07 10:22:13 volumio volumio[4426]: 22: 0xa0846c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Feb 07 10:22:13 volumio volumio[4426]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Feb 07 10:22:13 volumio volumio[4426]: 23: 0xa0846c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Feb 07 10:22:13 volumio volumio[4426]: at src/libstd/sys/unix/thread.rs:87 Feb 07 10:22:14 volumio sudo[5061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-07 10:21 Feb 07 10:22:14 volumio sudo[5061]: 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="c00a19098320053824010d132d6bfb39774a7d2f" VOLUMIO_FE_VERSION="e2f152c30d370c9c7c86508a02c0323bef5c243b" VOLUMIO_BE_VERSION="909cc76a7609428f005f8e2878227062cb5328a6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 17 00:18:22 CEST 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="cdde21c6e3d40ce352c051f661950d1b"