Aug 25 19:43:05 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:05.843: playing spotify:track:2Tx62r4EW8mCIh4eJsa2Cc "QUE PASO'" by "JOBBYDJ" [2:20 / 4:43] Aug 25 19:43:15 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:15.844: playing spotify:track:2Tx62r4EW8mCIh4eJsa2Cc "QUE PASO'" by "JOBBYDJ" [2:30 / 4:43] Aug 25 19:43:25 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:25.844: playing spotify:track:2Tx62r4EW8mCIh4eJsa2Cc "QUE PASO'" by "JOBBYDJ" [2:40 / 4:43] Aug 25 19:43:28 volumio volumio[995]: info: CALLMETHOD: music_service soloist_connect saveSoloistSettings [object Object] Aug 25 19:43:28 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: soloist_connect , saveSoloistSettings Aug 25 19:43:28 volumio volumio[995]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Queue Aug 25 19:43:28 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 19:43:28 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , soloistMeteringWanted Aug 25 19:43:28 volumio volumio[995]: info: SoloistConnect: writeEnvFile cache_location="disk" cache_size_mb=1024 ceiling=1963 -> ramMb=0 CACHE_SIZE=1024 Aug 25 19:43:28 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 19:43:28 volumio sudo[44022]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart soloist.service Aug 25 19:43:28 volumio sudo[44022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 19:43:28 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:28.721: shutting down Aug 25 19:43:28 volumio systemd[1]: Stopping soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 25 19:43:28 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:28.724: api: websocket server stopped Aug 25 19:43:28 volumio launch-soloist.sh[42092]: 2026-08-25 19:43:28.751: stopped Aug 25 19:43:28 volumio systemd[1]: soloist.service: Deactivated successfully. Aug 25 19:43:28 volumio systemd[1]: Stopped soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 25 19:43:28 volumio systemd[1]: soloist.service: Consumed 36.348s CPU time. Aug 25 19:43:28 volumio systemd[1]: Starting soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Aug 25 19:43:28 volumio systemd[1]: Started soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Aug 25 19:43:28 volumio sudo[44022]: pam_unix(sudo:session): session closed for user root Aug 25 19:43:28 volumio volumio[995]: info: SoloistConnect: soloist daemon started Aug 25 19:43:28 volumio volumio[995]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Aug 25 19:43:28 volumio launch-soloist.sh[44035]: SoloistConnect: userspace=amd64 device=plug:volumio tlength_cap=500ms external_volume=true trim=0dB diag=off uname=x86_64 Aug 25 19:43:28 volumio launch-soloist.sh[44035]: SoloistConnect: interpreter=/data/soloist/sysroot/usr/lib64/ld-linux-x86-64.so.2 Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.021: soloist 1.3.7.491 build 1787680901 (20260825) (gb24005ef46) (linux/x86_64) Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.021: client expires in 89 days Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.051: api: websocket server listening on 127.0.0.1:9878 Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.051: ready, device_name=Volumio device_id=ad083936-df20-4e86-baef-f64ac51f587c Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.052: restoring session Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.052: running Aug 25 19:43:29 volumio launch-soloist.sh[44035]: 2026-08-25 19:43:29.972: logged in as 315btytorbxawmvizs6kl34xqtgy Aug 25 19:43:33 volumio volumio[995]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Aug 25 19:43:33 volumio volumio[995]: info: SoloistConnect: WebSocket connected Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Queue Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 19:43:33 volumio volumio[995]: info: SoloistConnect: hold yield: is_active=false hold=2000ms Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 19:43:33 volumio volumio[995]: info: VolumeController::SetAlsaVolume100 Aug 25 19:43:33 volumio volumio[995]: info: CoreStateMachine::pushState Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::servicePushState Aug 25 19:43:33 volumio volumio[995]: info: CoreStateMachine::pushState Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 19:43:33 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 25 19:43:34 volumio volumio[995]: info: CoreCommandRouter::servicePushState Aug 25 19:43:34 volumio volumio[995]: info: CoreStateMachine::pushState Aug 25 19:43:34 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 25 19:43:34 volumio volumio[995]: info: CoreCommandRouter::servicePushState Aug 25 19:43:34 volumio volumio[995]: info: CoreStateMachine::pushState Aug 25 19:43:34 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 25 19:43:35 volumio volumio[995]: verbose: UNSET VOLATILE: Service: soloist_connect Aug 25 19:43:47 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Aug 25 19:43:47 volumio volumio[995]: info: CorePlayQueue::getTrack 40 Aug 25 19:43:54 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Aug 25 19:43:55 volumio volumio[995]: info: CoreStateMachine::play index undefined Aug 25 19:43:55 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 19:43:55 volumio volumio[995]: info: CorePlayQueue::getTrack 40 Aug 25 19:43:55 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Aug 25 19:43:55 volumio volumio[995]: info: CorePlayQueue::getTrack 40 Aug 25 19:44:03 volumio volumio[995]: info: CoreCommandRouter::volumioNext Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::next Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::stop Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Aug 25 19:44:03 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::pushState Aug 25 19:44:03 volumio volumio[995]: info: CorePlayQueue::getTrack 40 Aug 25 19:44:03 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 19:44:03 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Aug 25 19:44:03 volumio volumio[995]: info: CoreStateMachine::serviceStop Aug 25 19:44:03 volumio volumio[995]: info: CorePlayQueue::getTrack 40 Aug 25 19:44:03 volumio volumio[995]: info: CoreCommandRouter::serviceStop Aug 25 19:44:03 volumio volumio[995]: error: WARNING: No stop method for service spop Aug 25 19:44:03 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 19:44:03 volumio volumio[995]: TypeError: Cannot read properties of undefined (reading 'then') Aug 25 19:44:03 volumio volumio[995]: at CoreStateMachine.next (/volumio/app/statemachine.js:1181:9) Aug 25 19:44:03 volumio volumio[995]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Aug 25 19:44:03 volumio volumio[995]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Aug 25 19:44:03 volumio volumio[995]: at Socket.emit (node:events:514:28) Aug 25 19:44:03 volumio volumio[995]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 25 19:44:03 volumio volumio[995]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 25 19:44:03 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 19:44:03 volumio sudo[44199]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 19:43' Aug 25 19:44:03 volumio sudo[44199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"