-- Logs begin at Wed 2025-02-05 17:27:31 UTC, end at Sat 2025-03-01 21:06:22 UTC. -- Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Authenticated as "temaar" ! Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Using alsa sink Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Metadata pipe established Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Country: "NL" Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Event: Volume { volume_to_mixer: 21626 } Mar 01 21:06:20 muziekstreamer systemd-journal[166]: Suppressed 2011 messages from /system.slice/volumio.service Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] 32.99916075379568 Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] Volume: Spotify:32.99916075379568 Volumio: 33 Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreStateMachine::pushState Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreStateMachine::getState Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreCommandRouter::volumioPushState Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 21:06:20 muziekstreamer sudo[21668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 01 21:06:20 muziekstreamer sudo[21668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 21:06:20 muziekstreamer sudo[21668]: pam_unix(sudo:session): session closed for user root Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Event: SessionActive { became_active_at: 1740863180958 } Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : SessionActive! Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Fetching autoplay context uri Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] A connect session has begun Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 292716564479975878233715094642457409865, audio_type: Track } } Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 292716564479975878233715094642457409865, audio_type: Track } } Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] Device palyback is active! Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreCommandRouter::volumioGetState Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreStateMachine::getState Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] Currently active: airplay Mar 01 21:06:20 muziekstreamer volumio[1025]: [SpotifyConnect] Stopping currently active service Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreCommandRouter::volumioStop Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreStateMachine::stop Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreStateMachine::serviceStop Mar 01 21:06:20 muziekstreamer volumio[1025]: info: CoreCommandRouter::serviceStop Mar 01 21:06:20 muziekstreamer volumio[1025]: error: WARNING: No stop method for service airplay Mar 01 21:06:20 muziekstreamer volumio[1025]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 21:06:20 muziekstreamer volumio[1025]: TypeError: Cannot read property 'then' of undefined Mar 01 21:06:20 muziekstreamer volumio[1025]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) Mar 01 21:06:20 muziekstreamer volumio[1025]: at emitNone (events.js:106:13) Mar 01 21:06:20 muziekstreamer volumio[1025]: at SpotConnEvents.emit (events.js:208:7) Mar 01 21:06:20 muziekstreamer volumio[1025]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) Mar 01 21:06:20 muziekstreamer volumio[1025]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) Mar 01 21:06:20 muziekstreamer volumio[1025]: at emitTwo (events.js:126:13) Mar 01 21:06:20 muziekstreamer volumio[1025]: at Socket.emit (events.js:214:7) Mar 01 21:06:20 muziekstreamer volumio[1025]: at UDP.onMessage [as onmessage] (dgram.js:659:8) Mar 01 21:06:20 muziekstreamer volumio[1025]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Loading with Spotify URI Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:track:7uCnDfUGb0vtjonHaSv6ka"> Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Resolving uri "spotify:station:track:7uCnDfUGb0vtjonHaSv6ka" Mar 01 21:06:20 muziekstreamer volumio[31779]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCBACQWr_AGy55KJtH3aXBlp_SdkzBJoqs1wiOFSZQxD2cPbXoI8R8m10-QIhpWvrJoDqTonSyrchLjqkkzR1v0HlZuU7fuPEoOLp_bh7IB-Qb4H1suboN46Ri7W_ujkoDe5FpADAefNyi7JYqtI4hHwgrwarC2VDb_y3e_EEShWzLtxboyj96W3EAjfGNogIwhJk9kMnitiN3pY86oh80a9JEO2mlP", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : (267933 ms) loaded Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed Mar 01 21:06:21 muziekstreamer volumio[31779]: 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 Mar 01 21:06:21 muziekstreamer volumio[31779]: stack backtrace: Mar 01 21:06:21 muziekstreamer volumio[31779]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:37i9dQZF1E8EqFxShiaw51"> Mar 01 21:06:21 muziekstreamer volumio[31779]: 0: 0xa1833c - backtrace::backtrace::libunwind::trace::hae21a072c81e5842 Mar 01 21:06:21 muziekstreamer volumio[31779]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/libunwind.rs:86 Mar 01 21:06:21 muziekstreamer volumio[31779]: 1: 0xa1833c - backtrace::backtrace::trace_unsynchronized::h0f9b260087e46e47 Mar 01 21:06:21 muziekstreamer volumio[31779]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.46/src/backtrace/mod.rs:66 Mar 01 21:06:21 muziekstreamer volumio[31779]: 2: 0xa1833c - std::sys_common::backtrace::_print_fmt::hbf1a59173a7860c3 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/sys_common/backtrace.rs:78 Mar 01 21:06:21 muziekstreamer volumio[31779]: 3: 0xa1833c - ::fmt::he1a5d6f378e506c4 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/sys_common/backtrace.rs:59 Mar 01 21:06:21 muziekstreamer volumio[31779]: 4: 0xa38d5c - core::fmt::write::hb37ae5a5e0b70623 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libcore/fmt/mod.rs:1076 Mar 01 21:06:21 muziekstreamer volumio[31779]: 5: 0xa11c64 - std::io::Write::write_fmt::ha24bb3f5a858327b Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/io/mod.rs:1537 Mar 01 21:06:21 muziekstreamer volumio[31779]: 6: 0xa1a974 - std::sys_common::backtrace::_print::h47b03aa1342833e3 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/sys_common/backtrace.rs:62 Mar 01 21:06:21 muziekstreamer volumio[31779]: 7: 0xa1a974 - std::sys_common::backtrace::print::h2217cbc390250439 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/sys_common/backtrace.rs:49 Mar 01 21:06:21 muziekstreamer volumio[31779]: 8: 0xa1a974 - std::panicking::default_hook::{{closure}}::h179f5229ea1c8e30 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/panicking.rs:198 Mar 01 21:06:21 muziekstreamer volumio[31779]: 9: 0xa1a640 - std::panicking::default_hook::h46ab82039cbc65eb Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/panicking.rs:217 Mar 01 21:06:21 muziekstreamer volumio[31779]: 10: 0xa1b054 - std::panicking::rust_panic_with_hook::h7326c48419bc7c33 Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/panicking.rs:526 Mar 01 21:06:21 muziekstreamer volumio[31779]: 11: 0xa1ac54 - rust_begin_unwind Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/panicking.rs:437 Mar 01 21:06:21 muziekstreamer volumio[31779]: 12: 0xa36594 - core::panicking::panic_fmt::ha292e19d5ae716ed Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libcore/panicking.rs:85 Mar 01 21:06:21 muziekstreamer volumio[31779]: 13: 0xa364f0 - core::panicking::panic::heb457f4fa750842b Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libcore/panicking.rs:50 Mar 01 21:06:21 muziekstreamer volumio[31779]: 14: 0x615ee8 - ::write::h5da62cd8da1289c3 Mar 01 21:06:21 muziekstreamer volumio[31779]: 15: 0x5ef3c4 - ::poll::h2bafd5dc8d40bf3f Mar 01 21:06:21 muziekstreamer volumio[31779]: 16: 0x4ffeec - futures::task_impl::std::set::h2116cb0e9a5165fe Mar 01 21:06:21 muziekstreamer volumio[31779]: 17: 0x4f4c6c - futures::task_impl::Spawn::poll_future_notify::h20a5cc82ac41f2fa Mar 01 21:06:21 muziekstreamer volumio[31779]: 18: 0x50e6a0 - futures::future::Future::wait::ha8819dd7a48bc248 Mar 01 21:06:21 muziekstreamer volumio[31779]: 19: 0x4f64b4 - std::sys_common::backtrace::__rust_begin_short_backtrace::habfd768f2a80dc30 Mar 01 21:06:21 muziekstreamer volumio[31779]: 20: 0x5155a8 - core::ops::function::FnOnce::call_once{{vtable.shim}}::h0dcfa92cb8b4ff47 Mar 01 21:06:21 muziekstreamer volumio[31779]: 21: 0xa1e46c - as core::ops::function::FnOnce>::call_once::hb2bcc0b96e754d10 Mar 01 21:06:21 muziekstreamer volumio[31779]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Mar 01 21:06:21 muziekstreamer volumio[31779]: 22: 0xa1e46c - as core::ops::function::FnOnce>::call_once::h2dd73f7c66408260 Mar 01 21:06:21 muziekstreamer volumio[31779]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 Mar 01 21:06:21 muziekstreamer volumio[31779]: 23: 0xa1e46c - std::sys::unix::thread::Thread::new::thread_start::h6fc0046e32ed87dc Mar 01 21:06:21 muziekstreamer volumio[31779]: at src/libstd/sys/unix/thread.rs:87 Mar 01 21:06:22 muziekstreamer sudo[21686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 21:05 Mar 01 21:06:22 muziekstreamer sudo[21686]: 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="c52efa8090336a1aa08c5caa4d93eb926a1a7bed" VOLUMIO_FE_VERSION="97a49ba890ceb3037f74abfccc14d256050d3ef6" VOLUMIO_BE_VERSION="8bb3137b5d493584edfbdbea0fb0075f47d9c7f6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 31 12:58:00 CET 2018" VOLUMIO_VERSION="2.502" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d0847620f095b456556d6c09b0a4a5d4"