-- Logs begin at Wed 2014-01-01 18:06:37 UTC, end at Sat 2024-05-04 16:50:29 UTC. -- May 04 16:49:02 volumionuc volumio[852]: info: Following Redirect to: http://plugins.volumio.workers.dev/plugins/volumio/i386/plugins.json May 04 16:49:02 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 04 16:49:02 volumionuc volumio[852]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 04 16:49:02 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 04 16:49:02 volumionuc volumio[852]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio May 04 16:49:02 volumionuc volumio[852]: info: Received Get System Version May 04 16:49:02 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 04 16:49:13 volumionuc volumio[852]: info: Following Redirect to: http://plugins.volumio.workers.dev/plugins/volumio/i386/plugins.json May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: Retrieving Cloud Streaming UI May 04 16:49:28 volumionuc volumio[852]: info: Getting Tidal Cloud Configuration May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: Getting Qobuz Cloud Configuration May 04 16:49:28 volumionuc volumio[852]: info: Asking plugin for UI Config May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: Getting Spotify Cloud Configuration May 04 16:49:28 volumionuc volumio[852]: info: Asking plugin for UI Config May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: Saving Spotify Acccount May 04 16:49:28 volumionuc volumio[852]: info: Got it May 04 16:49:28 volumionuc volumio[852]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 04 16:49:28 volumionuc volumio[852]: info: Got Tidal Cloud Configuration May 04 16:49:28 volumionuc volumio[852]: info: Got it May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::volumioGetBrowseSources May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:28 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 04 16:49:32 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:36 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:40 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:44 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:48 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:52 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:49:56 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:00 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:04 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:08 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:11 volumionuc volumio[4107]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Authenticated as "rytnbu1tycowv7f47cr62ys15" ! May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Setting up new mixer: card:hw:5 mixer:SOtM Clock Selector index:0 May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(-12700)[dB]) -- max: 127 (MilliBel(0)[dB]) HW: true May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Using alsa sink May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Country: "BE" May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Metadata pipe established May 04 16:50:12 volumionuc volumio[4107]: [Vollibrespot] : Event: Volume { volume_to_mixer: 49151 } May 04 16:50:12 volumionuc volumio[852]: [SpotifyConnect] 74.99961852445259 May 04 16:50:12 volumionuc volumio[852]: [SpotifyConnect] Volume: Spotify:74.99961852445259 Volumio: 75 May 04 16:50:12 volumionuc volumio[852]: info: CoreStateMachine::pushState May 04 16:50:12 volumionuc volumio[852]: info: CoreCommandRouter::volumioPushState May 04 16:50:12 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 16:50:13 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:16 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:20 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:24 volumionuc volumio[852]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Event: SessionActive { became_active_at: 1714841427624 } May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : SessionActive! May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Fetching autoplay context uri May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 234212905805745689839502975107315818461, audio_type: Track } } May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 234212905805745689839502975107315818461, audio_type: Track } } May 04 16:50:27 volumionuc volumio[852]: [SpotifyConnect] A connect session has begun May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Autoplay uri resolved to <"spotify:station:playlist:1rkqusAdsRthBcGGJa3an4"> May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Resolving uri "spotify:station:playlist:1rkqusAdsRthBcGGJa3an4" May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQCzd-_9J1yQudQIxRgbiBVCu4ZwvfP-APr2DWODlZrFgeunyrkMU2sHPXOfFsYZ_qyj39K7BIvMv9QbIbiz71ibnWdddG9EK7wKOnt4fYZzSp0JSTOHjUSh4qAm5dchx2VzwAjtoDf8G1lv11PUFwMYDERss1tW0vvS77kBdIqLmaCo1aRHVXnArp6kXXOStCp0-cRQgN5gq4By-crXyMPEpg", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : Loading with Spotify URI May 04 16:50:27 volumionuc volumio[852]: [SpotifyConnect] Device palyback is active! May 04 16:50:27 volumionuc volumio[4107]: [Vollibrespot] : (211510 ms) loaded May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Alsa error PCM open ALSA function 'snd_pcm_open' failed with error 'EBUSY: Device or resource busy' May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Could not start audio: Alsa error: PCM open failed May 04 16:50:28 volumionuc volumio[4107]: 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 04 16:50:28 volumionuc volumio[4107]: stack backtrace: May 04 16:50:28 volumionuc volumio[852]: info: CoreCommandRouter::volumioGetState May 04 16:50:28 volumionuc volumio[852]: [SpotifyConnect] Currently active: tidalconnect May 04 16:50:28 volumionuc volumio[852]: [SpotifyConnect] Stopping currently active service May 04 16:50:28 volumionuc volumio[852]: info: CoreCommandRouter::volumioStop May 04 16:50:28 volumionuc volumio[852]: info: CoreStateMachine::stop May 04 16:50:28 volumionuc volumio[852]: info: CoreStateMachine::serviceStop May 04 16:50:28 volumionuc volumio[852]: info: CoreCommandRouter::serviceStop May 04 16:50:28 volumionuc volumio[852]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 16:50:28 volumionuc vtcs[4131]: [2024-05-04 16:50:28.159] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:0 May 04 16:50:28 volumionuc volumio[852]: TypeError: Cannot read property 'then' of undefined May 04 16:50:28 volumionuc volumio[852]: at SpotConnEvents.SpotConn.on (/data/plugins/music_service/volspotconnect2/index.js:143:23) May 04 16:50:28 volumionuc volumio[852]: at emitNone (events.js:106:13) May 04 16:50:28 volumionuc volumio[852]: at SpotConnEvents.emit (events.js:208:7) May 04 16:50:28 volumionuc volumio[852]: at SpotConnEvents.parseData (/data/plugins/music_service/volspotconnect2/SpotConnController.js:73:16) May 04 16:50:28 volumionuc volumio[852]: at Socket.SpotConnEvents._udpsource.on.msg (/data/plugins/music_service/volspotconnect2/SpotConnController.js:15:12) May 04 16:50:28 volumionuc volumio[852]: at emitTwo (events.js:126:13) May 04 16:50:28 volumionuc volumio[852]: at Socket.emit (events.js:214:7) May 04 16:50:28 volumionuc volumio[852]: at UDP.onMessage [as onmessage] (dgram.js:659:8) May 04 16:50:28 volumionuc volumio[852]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 16:50:28 volumionuc volumio[4107]: [Vollibrespot] : Resolved 50 tracks from <"spotify:playlist:1rkqusAdsRthBcGGJa3an4"> May 04 16:50:28 volumionuc volumio[4107]: 0: 0xb7397e38 - backtrace::backtrace::libunwind::trace::ha66f0a58dafef379 May 04 16:50:28 volumionuc volumio[4107]: at /cargo/registry/src/github.com-1285ae84e5963aae/backtrace-0.3.46/src/backtrace/libunwind.rs:86 May 04 16:50:28 volumionuc volumio[4107]: 1: 0xb7397e38 - backtrace::backtrace::trace_unsynchronized::h1af62022ad7ddee3 May 04 16:50:28 volumionuc volumio[4107]: at /cargo/registry/src/github.com-1285ae84e5963aae/backtrace-0.3.46/src/backtrace/mod.rs:66 May 04 16:50:28 volumionuc volumio[4107]: 2: 0xb7397e38 - std::sys_common::backtrace::_print_fmt::hb97d84e219f64b39 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/sys_common/backtrace.rs:78 May 04 16:50:28 volumionuc volumio[4107]: 3: 0xb7397e38 - ::fmt::h100f5a82407b68e4 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/sys_common/backtrace.rs:59 May 04 16:50:28 volumionuc volumio[4107]: 4: 0xb73c1e80 - core::fmt::write::he118d0604e33df81 May 04 16:50:28 volumionuc volumio[4107]: at src/libcore/fmt/mod.rs:1076 May 04 16:50:28 volumionuc volumio[4107]: 5: 0xb739171c - std::io::Write::write_fmt::h003436247c3fc214 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/io/mod.rs:1537 May 04 16:50:28 volumionuc volumio[4107]: 6: 0xb739a76e - std::sys_common::backtrace::_print::h83f536c912b08532 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/sys_common/backtrace.rs:62 May 04 16:50:28 volumionuc volumio[4107]: 7: 0xb739a76e - std::sys_common::backtrace::print::h89499a5fd474cd45 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/sys_common/backtrace.rs:49 May 04 16:50:28 volumionuc volumio[4107]: 8: 0xb739a76e - std::panicking::default_hook::{{closure}}::h105742511edf80c2 May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/panicking.rs:198 May 04 16:50:28 volumionuc volumio[4107]: 9: 0xb739a498 - std::panicking::default_hook::h4b1ff4bee1a0989c May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/panicking.rs:217 May 04 16:50:28 volumionuc volumio[4107]: 10: 0xb739ae16 - std::panicking::rust_panic_with_hook::h403abc20e0fb824f May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/panicking.rs:526 May 04 16:50:28 volumionuc volumio[4107]: 11: 0xb739a9c8 - rust_begin_unwind May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/panicking.rs:437 May 04 16:50:28 volumionuc volumio[4107]: 12: 0xb73bfab0 - core::panicking::panic_fmt::h280e963ddd15a643 May 04 16:50:28 volumionuc volumio[4107]: at src/libcore/panicking.rs:85 May 04 16:50:28 volumionuc volumio[4107]: 13: 0xb73bf9ec - core::panicking::panic::h9ccc1b62a8119bd7 May 04 16:50:28 volumionuc volumio[4107]: at src/libcore/panicking.rs:50 May 04 16:50:28 volumionuc volumio[4107]: 14: 0xb6f94f8d - ::write::h3581324fecd47df1 May 04 16:50:28 volumionuc volumio[4107]: 15: 0xb6f6dac6 - ::poll::hc21f5ecf7ce2e698 May 04 16:50:28 volumionuc volumio[4107]: 16: 0xb6e83e66 - futures::task_impl::std::set::h7d1d91c2a2b6f5b8 May 04 16:50:28 volumionuc volumio[4107]: 17: 0xb6e79055 - futures::task_impl::Spawn::poll_future_notify::h66f1553db90c4a22 May 04 16:50:28 volumionuc volumio[4107]: 18: 0xb6e9176c - futures::future::Future::wait::hfe836d397a4aec59 May 04 16:50:28 volumionuc volumio[4107]: 19: 0xb6e7a6e6 - std::sys_common::backtrace::__rust_begin_short_backtrace::h5ece287f9f454f8f May 04 16:50:28 volumionuc volumio[4107]: 20: 0xb6e984ab - core::ops::function::FnOnce::call_once{{vtable.shim}}::he930b09a4d23e04c May 04 16:50:28 volumionuc volumio[4107]: 21: 0xb739e2ef - as core::ops::function::FnOnce>::call_once::h248edc50ac6013fc May 04 16:50:28 volumionuc volumio[4107]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 May 04 16:50:28 volumionuc volumio[4107]: 22: 0xb739e2ef - as core::ops::function::FnOnce>::call_once::h97a26f5b6bb26bb1 May 04 16:50:28 volumionuc volumio[4107]: at /rustc/04488afe34512aa4c33566eb16d8c912a3ae04f9/src/liballoc/boxed.rs:1081 May 04 16:50:28 volumionuc volumio[4107]: 23: 0xb739e2ef - std::sys::unix::thread::Thread::new::thread_start::h74eb381cbdc8573a May 04 16:50:28 volumionuc volumio[4107]: at src/libstd/sys/unix/thread.rs:87 May 04 16:50:28 volumionuc volumio[4107]: 24: 0xb6c7c27a - start_thread May 04 16:50:28 volumionuc volumio[4107]: 25: 0xb6b89366 - __clone May 04 16:50:28 volumionuc volumio[4107]: 26: 0x0 - May 04 16:50:29 volumionuc sudo[10910]: PAM unable to dlopen(pam_systemd.so): /lib/security/pam_systemd.so: cannot open shared object file: No such file or directory May 04 16:50:29 volumionuc sudo[10910]: PAM adding faulty module: pam_systemd.so May 04 16:50:29 volumionuc sudo[10910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-04 16:49 May 04 16:50:29 volumionuc sudo[10910]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"