-- Logs begin at Wed 2026-08-26 17:35:22 AEST, end at Wed 2026-08-26 17:42:07 AEST. -- Aug 26 17:41:00 rivoplus sudo[6087]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:41:00 rivoplus sudo[6087]: pam_unix(sudo:session): session closed for user root Aug 26 17:41:00 rivoplus sudo[6089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:41:00 rivoplus sudo[6089]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:41:00 rivoplus systemd[1]: Stopping Music Player Daemon... Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 17:41:00 rivoplus systemd[1]: mpd.service: Succeeded. Aug 26 17:41:00 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:41:00 rivoplus systemd[1]: Stopped Music Player Daemon. Aug 26 17:41:00 rivoplus volumio[3204]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 26 17:41:00 rivoplus volumio[3204]: info: MPD Permissions set Aug 26 17:41:00 rivoplus systemd[1]: Starting Music Player Daemon... Aug 26 17:41:00 rivoplus volumio[3204]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 26 17:41:00 rivoplus volumio[3204]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 26 17:41:00 rivoplus sudo[6099]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:41:00 rivoplus sudo[6099]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 17:41:00 rivoplus sudo[6099]: pam_unix(sudo:session): session closed for user root Aug 26 17:41:01 rivoplus mpd[6108]: Aug 26 17:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 17:41:02 rivoplus systemd[1]: Started Music Player Daemon. Aug 26 17:41:02 rivoplus sudo[6089]: pam_unix(sudo:session): session closed for user root Aug 26 17:41:02 rivoplus volumio[3204]: error: updateQueue error: null Aug 26 17:41:13 rivoplus volumio[3204]: info: CoreCommandRouter::volumioGetState Aug 26 17:41:13 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:19 rivoplus volumio[3204]: info: CoreCommandRouter::volumioGetQueue Aug 26 17:41:19 rivoplus volumio[3204]: info: CoreStateMachine::getQueue Aug 26 17:41:19 rivoplus volumio[3204]: info: CorePlayQueue::getQueue Aug 26 17:41:22 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 26 17:41:22 rivoplus volumio[3204]: info: CURURI: artists:// Aug 26 17:41:22 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:30 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 26 17:41:30 rivoplus volumio[3204]: info: CURURI: artists://Cat%20Stevens Aug 26 17:41:30 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:30 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:30 rivoplus volumio[3204]: info: Preloading song: music-library/INTERNAL/07 - Morning Has Broken.flac Aug 26 17:41:30 rivoplus volumio[3204]: info: Preloading song: music-library/INTERNAL/09 - Moonshadow.flac Aug 26 17:41:30 rivoplus volumio[3204]: info: Exploding uri music-library/INTERNAL/07 - Morning Has Broken.flac in service mpd Aug 26 17:41:30 rivoplus volumio[3204]: info: ALBUMART /albumart?cacheid=689&web=Cat%20Stevens/Teaser%20and%20the%20Firecat/extralarge&path=%2Fmnt%2FINTERNAL%2F07%20-%20Morning%20Has%20Broken.flac&metadata=false Aug 26 17:41:30 rivoplus volumio[3204]: info: URI /mnt/INTERNAL/07 - Morning Has Broken.flac Aug 26 17:41:30 rivoplus volumio[3204]: info: Exploding uri music-library/INTERNAL/09 - Moonshadow.flac in service mpd Aug 26 17:41:30 rivoplus volumio[3204]: info: ALBUMART /albumart?cacheid=689&web=Cat%20Stevens/Teaser%20and%20the%20Firecat/extralarge&path=%2Fmnt%2FINTERNAL%2F09%20-%20Moonshadow.flac&metadata=false Aug 26 17:41:30 rivoplus volumio[3204]: info: URI /mnt/INTERNAL/09 - Moonshadow.flac Aug 26 17:41:31 rivoplus volumio[3204]: info: Executing endpoint metavolumio Aug 26 17:41:31 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 17:41:31 rivoplus volumio[3204]: info: Executing endpoint getSimilarArtists Aug 26 17:41:31 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 17:41:33 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::ClearQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::stop Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::clearPlayQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::saveQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::addQueueItems Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::addQueueItems Aug 26 17:41:33 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:33 rivoplus volumio[3204]: info: Adding Item to queue: music-library/INTERNAL/07 - Morning Has Broken.flac Aug 26 17:41:33 rivoplus volumio[3204]: info: Using cached record of: music-library/INTERNAL/07 - Morning Has Broken.flac Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::saveQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::updateTrackBlock Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrackBlock Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPlay Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::play index 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::addQueueItems Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::addQueueItems Aug 26 17:41:33 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:41:33 rivoplus volumio[3204]: info: Adding Item to queue: music-library/INTERNAL/09 - Moonshadow.flac Aug 26 17:41:33 rivoplus volumio[3204]: info: Using cached record of: music-library/INTERNAL/09 - Moonshadow.flac Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::stop Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPushQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::saveQueue Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::play index undefined Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::updateTrackBlock Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrackBlock Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/07 - Morning Has Broken.flac Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand stop took 11 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 17:41:33 rivoplus volumio[3204]: info: Aug 26 17:41:33 rivoplus volumio[3204]: ---------------------------- MPD announces system playlist update Aug 26 17:41:33 rivoplus volumio[3204]: info: Ignoring MPD Status Update Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand clear took 4 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/07 - Morning Has Broken.flac" Aug 26 17:41:33 rivoplus volumio[3204]: error: updateQueue error: null Aug 26 17:41:33 rivoplus volumio[3204]: info: Aug 26 17:41:33 rivoplus volumio[3204]: ---------------------------- MPD announces system playlist update Aug 26 17:41:33 rivoplus volumio[3204]: info: Ignoring MPD Status Update Aug 26 17:41:33 rivoplus volumio[3204]: info: ------------------------------ 8ms Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand add "INTERNAL/07 - Morning Has Broken.flac" took 5 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand play Aug 26 17:41:33 rivoplus volumio[3204]: info: ------------------------------ 7ms Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand play took 5 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: info: Aug 26 17:41:33 rivoplus volumio[3204]: ---------------------------- MPD announces state update: player Aug 26 17:41:33 rivoplus volumio[3204]: info: ControllerMpd::getState Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:41:33 rivoplus volumio[3204]: info: Aug 26 17:41:33 rivoplus volumio[3204]: ---------------------------- MPD announces state update: player Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand status took 99 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: info: ControllerMpd::getState Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::parseState Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand status took 5 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::parseState Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:41:33 rivoplus volumio[3204]: info: ControllerMpd::pushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::servicePushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Morning Has Broken","artist":"Cat Stevens","album":"Teaser and the Firecat","uri":"INTERNAL/07 - Morning Has Broken.flac","trackType":"flac"} Aug 26 17:41:33 rivoplus volumio[3204]: verbose: CURRENT POSITION 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::syncState stateService play Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::syncState currentStatus stop Aug 26 17:41:33 rivoplus volumio[3204]: info: ------------------------------ 118ms Aug 26 17:41:33 rivoplus volumio[3204]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 26 17:41:33 rivoplus volumio[3204]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:41:33 rivoplus volumio[3204]: info: ControllerMpd::pushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::servicePushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":199,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1458 Kbps","isStreaming":false,"title":"Morning Has Broken","artist":"Cat Stevens","album":"Teaser and the Firecat","uri":"INTERNAL/07 - Morning Has Broken.flac","trackType":"flac"} Aug 26 17:41:33 rivoplus volumio[3204]: verbose: CURRENT POSITION 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::syncState stateService play Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:41:33 rivoplus volumio[3204]: info: Received an update from plugin. extracting info from payload Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::pushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioGetState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:41:33 rivoplus volumio[3204]: info: MRS: Pushing multiroomSync output Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreStateMachine::pushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioPushState Aug 26 17:41:33 rivoplus volumio[3204]: info: CoreCommandRouter::volumioGetState Aug 26 17:41:33 rivoplus volumio[3204]: info: CorePlayQueue::getTrack 0 Aug 26 17:41:33 rivoplus volumio[3204]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:41:33 rivoplus volumio[3204]: info: MRS: Pushing multiroomSync output Aug 26 17:41:33 rivoplus volumio[3204]: info: ------------------------------ 78ms Aug 26 17:41:33 rivoplus volumio[3204]: info: Signalling Playback active due to playback status change Aug 26 17:41:33 rivoplus volumio[3204]: info: Signalling Playback active due to playback status change Aug 26 17:41:33 rivoplus volumio[3204]: info: Updating RAAT Signal Path Aug 26 17:41:33 rivoplus volumio[3204]: info: Updating RAAT Signal Path Aug 26 17:41:33 rivoplus volumio[3204]: info: MCU Signalled Playback Active Aug 26 17:42:03 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 26 17:42:03 rivoplus volumio[3204]: info: CURURI: artists://10cc Aug 26 17:42:03 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:42:03 rivoplus volumio[3204]: info: Preload queue cleared Aug 26 17:42:03 rivoplus volumio[3204]: info: Preloading song: music-library/INTERNAL/02 - I'm Not In Love.dsf Aug 26 17:42:03 rivoplus volumio[3204]: info: Exploding uri music-library/INTERNAL/02 - I'm Not In Love.dsf in service mpd Aug 26 17:42:03 rivoplus volumio[3204]: info: ALBUMART /albumart?cacheid=689&web=10cc/The%20Original%20Soundtrack/extralarge&path=%2Fmnt%2FINTERNAL%2F02%20-%20I'm%20Not%20In%20Love.dsf&metadata=false Aug 26 17:42:03 rivoplus volumio[3204]: info: URI /mnt/INTERNAL/02 - I'm Not In Love.dsf Aug 26 17:42:03 rivoplus volumio[3204]: info: Executing endpoint getSimilarArtists Aug 26 17:42:03 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Aug 26 17:42:03 rivoplus volumio[3204]: info: Executing endpoint metavolumio Aug 26 17:42:03 rivoplus volumio[3204]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 26 17:42:06 rivoplus volumio[3204]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:42:06 rivoplus volumio[3204]: Error: connect ECONNREFUSED 158.69.209.125:443 Aug 26 17:42:06 rivoplus volumio[3204]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 26 17:42:06 rivoplus volumio[3204]: errno: -111, Aug 26 17:42:06 rivoplus volumio[3204]: code: 'ECONNREFUSED', Aug 26 17:42:06 rivoplus volumio[3204]: syscall: 'connect', Aug 26 17:42:06 rivoplus volumio[3204]: address: '158.69.209.125', Aug 26 17:42:06 rivoplus volumio[3204]: port: 443 Aug 26 17:42:06 rivoplus volumio[3204]: } Aug 26 17:42:06 rivoplus volumio[3204]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:42:07 rivoplus sudo[6303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 17:41 Aug 26 17:42:07 rivoplus sudo[6303]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"