-- 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"