-- Logs begin at Sun 2025-06-15 14:21:03 +07, end at Sun 2025-06-15 15:24:08 +07. -- Jun 15 15:23:20 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 15:23:20 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 15:23:20 rivoplus volumio[3182]: info: Discovery: Getting this device information Jun 15 15:23:20 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:20 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 15:23:32 rivoplus ntpd[20849]: Soliciting pool server 103.70.115.65 Jun 15 15:23:34 rivoplus ntpd[20849]: Soliciting pool server 103.184.124.254 Jun 15 15:23:37 rivoplus ntpd[20849]: Soliciting pool server 103.199.19.135 Jun 15 15:23:39 rivoplus ntpd[20849]: Soliciting pool server 115.165.161.155 Jun 15 15:23:50 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 15:23:50 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 15:23:50 rivoplus volumio[3182]: info: Discovery: Getting this device information Jun 15 15:23:50 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:50 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 15:23:51 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 0 Jun 15 15:23:51 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 1 Jun 15 15:23:51 rivoplus volumio[3182]: info: Prefetching next song Jun 15 15:23:51 rivoplus volumio[3182]: info: Doing Prefetch in UPNP Jun 15 15:23:51 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/9520.wav" Jun 15 15:23:51 rivoplus volumio[3182]: info: Jun 15 15:23:51 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:51 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:51 rivoplus volumio[3182]: info: sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/9520.wav" took 7 milliseconds Jun 15 15:23:51 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 15 15:23:51 rivoplus volumio[3182]: info: Jun 15 15:23:51 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:51 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:51 rivoplus volumio[3182]: info: Jun 15 15:23:51 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:51 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:51 rivoplus volumio[3182]: info: ------------------------------ 12ms Jun 15 15:23:51 rivoplus volumio[3182]: info: sendMpdCommand consume 1 took 10 milliseconds Jun 15 15:23:51 rivoplus volumio[3182]: info: ------------------------------ 7ms Jun 15 15:23:51 rivoplus volumio[3182]: info: ------------------------------ 5ms Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:55 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces state update: player Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::getState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand status Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:55 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces state update: player Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::getState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand status Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces system playlist update Jun 15 15:23:55 rivoplus volumio[3182]: info: Ignoring MPD Status Update Jun 15 15:23:55 rivoplus volumio[3182]: info: Jun 15 15:23:55 rivoplus volumio[3182]: ---------------------------- MPD announces state update: player Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::getState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand status Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 13ms Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand status took 10 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 9ms Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand status took 7 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 6ms Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand status took 5 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseState Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseTrackInfo Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseTrackInfo Jun 15 15:23:55 rivoplus volumio[3182]: verbose: ControllerMpd::parseTrackInfo Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::servicePushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 0 Jun 15 15:23:55 rivoplus volumio[3182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105,"duration":375,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9520.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/9520.wav","trackType":"wav"} Jun 15 15:23:55 rivoplus volumio[3182]: verbose: CURRENT POSITION 0 Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState stateService play Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState currentStatus play Jun 15 15:23:55 rivoplus volumio[3182]: info: Received an update from plugin. extracting info from payload Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::servicePushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 0 Jun 15 15:23:55 rivoplus volumio[3182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105,"duration":375,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9520.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/9520.wav","trackType":"wav"} Jun 15 15:23:55 rivoplus volumio[3182]: verbose: CURRENT POSITION 0 Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState stateService play Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState currentStatus play Jun 15 15:23:55 rivoplus volumio[3182]: info: Received an update from plugin. extracting info from payload Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: ControllerMpd::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::servicePushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 0 Jun 15 15:23:55 rivoplus volumio[3182]: verbose: STATE SERVICE {"status":"play","position":0,"seek":105,"duration":375,"samplerate":"48 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"9520.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/9520.wav","trackType":"wav"} Jun 15 15:23:55 rivoplus volumio[3182]: verbose: CURRENT POSITION 0 Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState stateService play Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::syncState currentStatus play Jun 15 15:23:55 rivoplus volumio[3182]: info: Received an update from plugin. extracting info from payload Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:55 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 83ms Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 81ms Jun 15 15:23:55 rivoplus volumio[3182]: info: ------------------------------ 80ms Jun 15 15:23:55 rivoplus volumio[3182]: info: CoreStateMachine::startPlaybackTimer Jun 15 15:23:55 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 1 Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreStateMachine::pushState Jun 15 15:23:56 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 1 Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreCommandRouter::volumioPushState Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetState Jun 15 15:23:56 rivoplus volumio[3182]: info: CorePlayQueue::getTrack 1 Jun 15 15:23:56 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output update for this device Jun 15 15:23:56 rivoplus volumio[3182]: info: MRS: Pushing multiroomSync output Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreCommandRouter::volumioGetQueue Jun 15 15:23:56 rivoplus volumio[3182]: info: CoreStateMachine::getQueue Jun 15 15:23:56 rivoplus volumio[3182]: info: CorePlayQueue::getQueue Jun 15 15:23:58 rivoplus volumio[3182]: info: Executing endpoint metavolumio Jun 15 15:23:58 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 15:23:58 rivoplus volumio[3182]: info: Executing endpoint metavolumio Jun 15 15:23:58 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 15:23:58 rivoplus volumio[3182]: info: Executing endpoint metavolumio Jun 15 15:23:58 rivoplus volumio[3182]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 15:24:05 rivoplus volumio[3182]: error: Failed request for metavolumio API Jun 15 15:24:05 rivoplus volumio[3182]: error: Failed request for metavolumio API Jun 15 15:24:07 rivoplus volumio[3182]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 15:24:07 rivoplus volumio[3182]: Error: connect ETIMEDOUT 151.101.194.79:443 Jun 15 15:24:07 rivoplus volumio[3182]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 15 15:24:07 rivoplus volumio[3182]: errno: -110, Jun 15 15:24:07 rivoplus volumio[3182]: code: 'ETIMEDOUT', Jun 15 15:24:07 rivoplus volumio[3182]: syscall: 'connect', Jun 15 15:24:07 rivoplus volumio[3182]: address: '151.101.194.79', Jun 15 15:24:07 rivoplus volumio[3182]: port: 443 Jun 15 15:24:07 rivoplus volumio[3182]: } Jun 15 15:24:07 rivoplus volumio[3182]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 15:24:08 rivoplus sudo[21599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 15:23 Jun 15 15:24:08 rivoplus sudo[21599]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:41:30 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="e2256d79dd6d0ae59eb47f10e57a5e95"