-- Logs begin at Sat 2025-05-17 13:10:35 +07, end at Sat 2025-05-17 16:57:48 +07. -- May 17 16:56:17 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 17 16:56:17 rivoplus volumio[16722]: info: Preload queue cleared May 17 16:56:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 16:56:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 16:56:24 rivoplus volumio[16722]: info: Discovery: Getting this device information May 17 16:56:24 rivoplus volumio[16722]: info: CoreCommandRouter::volumioGetState May 17 16:56:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 16:56:30 rivoplus ntpd[27716]: Soliciting pool server 103.70.115.65 May 17 16:56:32 rivoplus volumio[16722]: error: error May 17 16:56:37 rivoplus ntpd[27716]: Soliciting pool server 27.71.27.209 May 17 16:56:38 rivoplus ntpd[27716]: Soliciting pool server 27.71.27.209 May 17 16:56:38 rivoplus ntpd[27716]: Soliciting pool server 103.70.115.65 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPlay May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::play index 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::stop May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::stPlaybackTimer May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::updateTrackBlock May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrackBlock May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 0 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::serviceStop May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 0 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::serviceStop May 17 16:56:40 rivoplus volumio[16722]: info: [1747475800599] ControllerUPNPBrowser::stop May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand stop May 17 16:56:40 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand stop took 92 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::play index undefined May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::startPlaybackTimer May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: info: [1747475800695] ControllerUPNPBrowser::clearAddPlayTrack May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand stop May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 25 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand stop took 23 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 22 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 18 milliseconds May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand clear May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand clear took 4 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:50002/m/NDLNA/5856.dsf" May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5853.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5853.dsf","trackType":"dsf"} May 17 16:56:40 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService stop May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus stop May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: info: No code May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 16:56:40 rivoplus volumio[16722]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 83ms May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 79ms May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 77ms May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: MCU Signalled Playback Inactive May 17 16:56:40 rivoplus volumio[16722]: error: updateQueue error: null May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 59ms May 17 16:56:40 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:40 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:40 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:40 rivoplus volumio[16722]: error: updateQueue error: null May 17 16:56:40 rivoplus volumio[16722]: error: updateQueue error: null May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/5856.dsf" May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 44ms May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 43ms May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/5856.dsf" took 15 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand play May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces system playlist update May 17 16:56:40 rivoplus volumio[16722]: info: Ignoring MPD Status Update May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 52ms May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand play took 48 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 6ms May 17 16:56:40 rivoplus volumio[16722]: info: ------------------------------ 5ms May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 23 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 23 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 11 milliseconds May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: May 17 16:56:40 rivoplus volumio[16722]: ---------------------------- MPD announces state update: player May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::getState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand status May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 29 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 28 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 24 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 25 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 22 milliseconds May 17 16:56:40 rivoplus volumio[16722]: info: sendMpdCommand status took 20 milliseconds May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::parseState May 17 16:56:40 rivoplus volumio[16722]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:40 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus stop May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:40 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus play May 17 16:56:40 rivoplus volumio[16722]: info: Received an update from plugin. extracting info from payload May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:40 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:40 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:40 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":28,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:40 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus play May 17 16:56:40 rivoplus volumio[16722]: info: Received an update from plugin. extracting info from payload May 17 16:56:40 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:40 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:40 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 111ms May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 129ms May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 123ms May 17 16:56:41 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 62 milliseconds May 17 16:56:41 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 61 milliseconds May 17 16:56:41 rivoplus volumio[16722]: info: sendMpdCommand playlistinfo took 60 milliseconds May 17 16:56:41 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:41 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:41 rivoplus volumio[16722]: verbose: ControllerMpd::parseTrackInfo May 17 16:56:41 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:41 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:41 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:41 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus play May 17 16:56:41 rivoplus volumio[16722]: info: Received an update from plugin. extracting info from payload May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:41 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:41 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":354,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:41 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus play May 17 16:56:41 rivoplus volumio[16722]: info: Received an update from plugin. extracting info from payload May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: ControllerMpd::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::servicePushState May 17 16:56:41 rivoplus volumio[16722]: info: CorePlayQueue::getTrack 6 May 17 16:56:41 rivoplus volumio[16722]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":172,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"5856.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/5856.dsf","trackType":"dsf"} May 17 16:56:41 rivoplus volumio[16722]: verbose: CURRENT POSITION 6 May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState stateService play May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::syncState currentStatus play May 17 16:56:41 rivoplus volumio[16722]: info: Received an update from plugin. extracting info from payload May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: CoreStateMachine::pushState May 17 16:56:41 rivoplus volumio[16722]: info: CoreCommandRouter::volumioPushState May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output update for this device May 17 16:56:41 rivoplus volumio[16722]: info: MRS: Pushing multiroomSync output May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 197ms May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 186ms May 17 16:56:41 rivoplus volumio[16722]: info: ------------------------------ 186ms May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Updating RAAT Signal Path May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: Signalling Playback active due to playback status change May 17 16:56:41 rivoplus volumio[16722]: info: MCU Signalled Playback Active May 17 16:56:43 rivoplus volumio[16722]: info: Executing endpoint metavolumio May 17 16:56:43 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 16:56:54 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 16:56:54 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 16:56:54 rivoplus volumio[16722]: info: Discovery: Getting this device information May 17 16:56:54 rivoplus volumio[16722]: info: CoreCommandRouter::volumioGetState May 17 16:56:54 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 16:57:22 rivoplus volumio[16722]: error: error May 17 16:57:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 16:57:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 16:57:24 rivoplus volumio[16722]: info: Discovery: Getting this device information May 17 16:57:24 rivoplus volumio[16722]: info: CoreCommandRouter::volumioGetState May 17 16:57:24 rivoplus volumio[16722]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 16:57:36 rivoplus ntpd[27716]: Soliciting pool server 115.165.161.155 May 17 16:57:41 rivoplus ntpd[27716]: Soliciting pool server 103.184.124.254 May 17 16:57:44 rivoplus ntpd[27716]: Soliciting pool server 103.199.19.135 May 17 16:57:44 rivoplus ntpd[27716]: Soliciting pool server 27.71.27.209 May 17 16:57:48 rivoplus volumio[16722]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 16:57:48 rivoplus volumio[16722]: Error: connect ETIMEDOUT 151.101.130.79:443 May 17 16:57:48 rivoplus volumio[16722]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 17 16:57:48 rivoplus volumio[16722]: errno: -110, May 17 16:57:48 rivoplus volumio[16722]: code: 'ETIMEDOUT', May 17 16:57:48 rivoplus volumio[16722]: syscall: 'connect', May 17 16:57:48 rivoplus volumio[16722]: address: '151.101.130.79', May 17 16:57:48 rivoplus volumio[16722]: port: 443 May 17 16:57:48 rivoplus volumio[16722]: } May 17 16:57:48 rivoplus volumio[16722]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 16:57:48 rivoplus sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 16:56 May 17 16:57:48 rivoplus sudo[953]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:42:11 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="4f5519a96ae5ba7dcfda362cc37343d4"