-- Logs begin at Wed 2026-01-28 19:53:01 NZDT, end at Wed 2026-01-28 20:21:18 NZDT. -- Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::volumioSeek Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreStateMachine::seek Jan 28 20:20:00 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:00 rivoplus volumio[3108]: info: TRACKBLOCK {"uri":"mnt/NAS/The_Vault/Sting/Sacred Love (2003) [SACD] (PCM Stereo) (88x24)/12 - Sting - Shape Of My Heart (from ...All This Time ).flac","service":"mpd","name":"12 - Shape Of My Heart (from '...All This Time')","artist":"Sting","album":"Sacred Love","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=11&web=Sting/Sacred%20Love/extralarge&path=%2FNAS%2FThe_Vault%2FSting%2FSacred%20Love%20(2003)%20%5BSACD%5D%20(PCM%20Stereo)%20(88x24)&metadata=false","duration":139,"samplerate":"88.2 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreStateMachine::startPlaybackTimer Jan 28 20:20:00 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 28 20:20:00 rivoplus volumio[3108]: info: ControllerMpd::seek Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:00 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 20:20:00 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:00 rivoplus volumio[3108]: error: null Jan 28 20:20:00 rivoplus volumio[3108]: info: Jan 28 20:20:00 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Jan 28 20:20:00 rivoplus volumio[3108]: info: ControllerMpd::getState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 28 20:20:00 rivoplus volumio[3108]: info: Jan 28 20:20:00 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Jan 28 20:20:00 rivoplus volumio[3108]: info: ControllerMpd::getState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 28 20:20:00 rivoplus volumio[3108]: info: Jan 28 20:20:00 rivoplus volumio[3108]: ---------------------------- MPD announces state update: player Jan 28 20:20:00 rivoplus volumio[3108]: info: ControllerMpd::getState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand status Jan 28 20:20:00 rivoplus volumio[3108]: info: sendMpdCommand status took 162 milliseconds Jan 28 20:20:00 rivoplus volumio[3108]: info: sendMpdCommand status took 158 milliseconds Jan 28 20:20:00 rivoplus volumio[3108]: info: sendMpdCommand status took 156 milliseconds Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::parseState Jan 28 20:20:00 rivoplus volumio[3108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 20:20:01 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 28 20:20:01 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 28 20:20:01 rivoplus volumio[3108]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 28 20:20:01 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 28 20:20:01 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 28 20:20:01 rivoplus volumio[3108]: verbose: ControllerMpd::parseTrackInfo Jan 28 20:20:01 rivoplus volumio[3108]: info: ControllerMpd::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38988,"duration":139,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2390 Kbps","isStreaming":false,"title":"Shape Of My Heart (from '...All This Time')","artist":"Sting","album":"Sacred Love","uri":"NAS/The_Vault/Sting/Sacred Love (2003) [SACD] (PCM Stereo) (88x24)/12 - Sting - Shape Of My Heart (from ...All This Time ).flac","trackType":"flac"} Jan 28 20:20:01 rivoplus volumio[3108]: verbose: CURRENT POSITION 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Jan 28 20:20:01 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: ControllerMpd::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38988,"duration":139,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2390 Kbps","isStreaming":false,"title":"Shape Of My Heart (from '...All This Time')","artist":"Sting","album":"Sacred Love","uri":"NAS/The_Vault/Sting/Sacred Love (2003) [SACD] (PCM Stereo) (88x24)/12 - Sting - Shape Of My Heart (from ...All This Time ).flac","trackType":"flac"} Jan 28 20:20:01 rivoplus volumio[3108]: verbose: CURRENT POSITION 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Jan 28 20:20:01 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: ControllerMpd::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::servicePushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38988,"duration":139,"samplerate":"88.2 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2390 Kbps","isStreaming":false,"title":"Shape Of My Heart (from '...All This Time')","artist":"Sting","album":"Sacred Love","uri":"NAS/The_Vault/Sting/Sacred Love (2003) [SACD] (PCM Stereo) (88x24)/12 - Sting - Shape Of My Heart (from ...All This Time ).flac","trackType":"flac"} Jan 28 20:20:01 rivoplus volumio[3108]: verbose: CURRENT POSITION 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState stateService play Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::syncState currentStatus play Jan 28 20:20:01 rivoplus volumio[3108]: info: Received an update from plugin. extracting info from payload Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreStateMachine::pushState Jan 28 20:20:01 rivoplus volumio[3108]: info: CorePlayQueue::getTrack 22 Jan 28 20:20:01 rivoplus volumio[3108]: info: CoreCommandRouter::volumioPushState Jan 28 20:20:01 rivoplus volumio[3108]: info: ------------------------------ 246ms Jan 28 20:20:01 rivoplus volumio[3108]: info: ------------------------------ 242ms Jan 28 20:20:01 rivoplus volumio[3108]: info: ------------------------------ 241ms Jan 28 20:21:04 rivoplus volumio[3108]: Searching plugin music_service/mpd Jan 28 20:21:04 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 20:21:05 rivoplus volumio[3108]: Searching plugin music_service/mpd Jan 28 20:21:05 rivoplus volumio[3108]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 20:21:12 rivoplus volumio[3108]: Upnp client error: Error: This socket has been ended by the other party Jan 28 20:21:12 rivoplus volumio[3108]: Upnp client error: Error: This socket has been ended by the other party Jan 28 20:21:12 rivoplus volumio[3108]: info: All search sources collected, pushing search results Jan 28 20:21:13 rivoplus volumio[3108]: info: All search sources collected, pushing search results Jan 28 20:21:17 rivoplus volumio[3108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 20:21:17 rivoplus volumio[3108]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 20:21:17 rivoplus volumio[3108]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jan 28 20:21:17 rivoplus volumio[3108]: errno: -111, Jan 28 20:21:17 rivoplus volumio[3108]: code: 'ECONNREFUSED', Jan 28 20:21:17 rivoplus volumio[3108]: syscall: 'connect', Jan 28 20:21:17 rivoplus volumio[3108]: address: '158.69.210.98', Jan 28 20:21:17 rivoplus volumio[3108]: port: 443 Jan 28 20:21:17 rivoplus volumio[3108]: } Jan 28 20:21:17 rivoplus volumio[3108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 20:21:18 rivoplus sudo[10608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 20:20 Jan 28 20:21:18 rivoplus sudo[10608]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:47:33 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="9e8aa5d6afb5e5d87a87bafae7aa5647"