-- Logs begin at Sat 2025-05-17 17:18:33 +07, end at Sat 2025-05-17 17:22:18 +07. -- May 17 17:21:00 rivoplus ntpd[3294]: Soliciting pool server 27.71.27.209 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPlay May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::play index 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::stop May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::stPlaybackTimer May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::updateTrackBlock May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrackBlock May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 0 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::serviceStop May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 0 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::serviceStop May 17 17:21:01 rivoplus volumio[3393]: info: [1747477261560] ControllerUPNPBrowser::stop May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand stop May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand stop took 42 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::play index undefined May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::startPlaybackTimer May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: [1747477261608] ControllerUPNPBrowser::clearAddPlayTrack May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand stop May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand stop took 5 milliseconds May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand clear May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 20 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand clear took 16 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 10 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 7 milliseconds May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:50002/m/NDLNA/2022.dsf" May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 17 17:21:01 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService stop May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus stop May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: No code May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 17 17:21:01 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService stop May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus stop May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus volumio[3393]: info: No code May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:01 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:01 rivoplus ntpd[3294]: Soliciting pool server 103.199.19.135 May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 136ms May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 135ms May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: error: updateQueue error: null May 17 17:21:01 rivoplus volumio[3393]: error: updateQueue error: null May 17 17:21:01 rivoplus volumio[3393]: error: updateQueue error: null May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 153ms May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 160 milliseconds May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/2022.dsf" May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 35ms May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 31ms May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:01 rivoplus volumio[3393]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 201ms May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/2022.dsf" took 74 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand play May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces system playlist update May 17 17:21:01 rivoplus volumio[3393]: info: Ignoring MPD Status Update May 17 17:21:01 rivoplus volumio[3393]: info: MCU Signalled Playback Inactive May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 23ms May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand play took 19 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 19ms May 17 17:21:01 rivoplus volumio[3393]: info: ------------------------------ 14ms May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 32 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 28 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 20 milliseconds May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: May 17 17:21:01 rivoplus volumio[3393]: ---------------------------- MPD announces state update: player May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::getState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand status May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 27 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 26 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 24 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 25 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 20 milliseconds May 17 17:21:01 rivoplus volumio[3393]: info: sendMpdCommand status took 14 milliseconds May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::parseState May 17 17:21:01 rivoplus volumio[3393]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:01 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:01 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:01 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus stop May 17 17:21:01 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:01 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:01 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:02 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:02 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus play May 17 17:21:02 rivoplus volumio[3393]: info: Received an update from plugin. extracting info from payload May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:02 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:02 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:02 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus play May 17 17:21:02 rivoplus volumio[3393]: info: Received an update from plugin. extracting info from payload May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 186ms May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 232ms May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 225ms May 17 17:21:02 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 163 milliseconds May 17 17:21:02 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 157 milliseconds May 17 17:21:02 rivoplus volumio[3393]: info: sendMpdCommand playlistinfo took 156 milliseconds May 17 17:21:02 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:02 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:02 rivoplus volumio[3393]: verbose: ControllerMpd::parseTrackInfo May 17 17:21:02 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:02 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:02 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:02 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus play May 17 17:21:02 rivoplus volumio[3393]: info: Received an update from plugin. extracting info from payload May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:02 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:02 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:02 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus play May 17 17:21:02 rivoplus volumio[3393]: info: Received an update from plugin. extracting info from payload May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: ControllerMpd::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::servicePushState May 17 17:21:02 rivoplus volumio[3393]: info: CorePlayQueue::getTrack 1 May 17 17:21:02 rivoplus volumio[3393]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154,"duration":288,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"2022.dsf","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/2022.dsf","trackType":"dsf"} May 17 17:21:02 rivoplus volumio[3393]: verbose: CURRENT POSITION 1 May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState stateService play May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::syncState currentStatus play May 17 17:21:02 rivoplus volumio[3393]: info: Received an update from plugin. extracting info from payload May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: CoreStateMachine::pushState May 17 17:21:02 rivoplus volumio[3393]: info: CoreCommandRouter::volumioPushState May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output update for this device May 17 17:21:02 rivoplus volumio[3393]: info: MRS: Pushing multiroomSync output May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 378ms May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 370ms May 17 17:21:02 rivoplus volumio[3393]: info: ------------------------------ 369ms May 17 17:21:02 rivoplus volumio[3393]: info: Signalling Playback active due to playback status change May 17 17:21:02 rivoplus volumio[3393]: info: MCU Signalled Playback Active May 17 17:21:03 rivoplus ntpd[3294]: Soliciting pool server 103.70.115.65 May 17 17:21:04 rivoplus volumio[3393]: info: Executing endpoint metavolumio May 17 17:21:04 rivoplus volumio[3393]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 17 17:21:04 rivoplus ntpd[3294]: Soliciting pool server 103.184.124.254 May 17 17:21:06 rivoplus volumio[3393]: error: Failed request for metavolumio API May 17 17:21:14 rivoplus volumio[3393]: error: error May 17 17:22:05 rivoplus volumio[3393]: error: error May 17 17:22:05 rivoplus ntpd[3294]: Soliciting pool server 103.184.124.254 May 17 17:22:06 rivoplus ntpd[3294]: Soliciting pool server 115.165.161.155 May 17 17:22:09 rivoplus ntpd[3294]: Soliciting pool server 27.71.27.209 May 17 17:22:10 rivoplus ntpd[3294]: Soliciting pool server 103.199.19.135 May 17 17:22:17 rivoplus volumio[3393]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 17:22:17 rivoplus volumio[3393]: Error: connect ETIMEDOUT 151.101.130.79:443 May 17 17:22:17 rivoplus volumio[3393]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 17 17:22:17 rivoplus volumio[3393]: errno: -110, May 17 17:22:17 rivoplus volumio[3393]: code: 'ETIMEDOUT', May 17 17:22:17 rivoplus volumio[3393]: syscall: 'connect', May 17 17:22:17 rivoplus volumio[3393]: address: '151.101.130.79', May 17 17:22:17 rivoplus volumio[3393]: port: 443 May 17 17:22:17 rivoplus volumio[3393]: } May 17 17:22:17 rivoplus volumio[3393]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 17:22:18 rivoplus sudo[4890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 17:21 May 17 17:22:18 rivoplus sudo[4890]: 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"