-- Logs begin at Fri 2025-05-23 18:25:24 +07, end at Mon 2025-05-26 15:19:25 +07. -- May 26 15:18:00 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 15:18:00 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:03 rivoplus ntpd[3427]: Soliciting pool server 115.165.161.155 May 26 15:18:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:18:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:18:04 rivoplus volumio[3447]: info: Discovery: Getting this device information May 26 15:18:04 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:18:08 rivoplus ntpd[3427]: Soliciting pool server 27.71.27.209 May 26 15:18:10 rivoplus ntpd[3427]: Soliciting pool server 115.165.161.155 May 26 15:18:18 rivoplus ntpd[3427]: Soliciting pool server 115.165.161.155 May 26 15:18:19 rivoplus volumio[3447]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.6 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetVisibleSources May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 26 15:18:19 rivoplus volumio[3447]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 26 15:18:19 rivoplus volumio[3447]: info: Received Get System Info May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:18:19 rivoplus volumio[3447]: info: Discovery: Getting this device information May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:19 rivoplus volumio[3447]: info: Listing playlists May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetQueue May 26 15:18:19 rivoplus volumio[3447]: info: CoreStateMachine::getQueue May 26 15:18:19 rivoplus volumio[3447]: info: CorePlayQueue::getQueue May 26 15:18:19 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 26 15:18:27 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 15:18:27 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4250 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4258 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4256 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4249 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4247 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4259 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4254 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4255 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4248 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4252 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4253 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4244 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4260 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4251 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4243 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4245 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4257 May 26 15:18:27 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4246 May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4250 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4258 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4256 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4249 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4247 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4259 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4254 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4255 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4248 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4252 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4253 in service upnp_browser May 26 15:18:27 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4244 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4260 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4251 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4243 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4245 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4257 in service upnp_browser May 26 15:18:28 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4246 in service upnp_browser May 26 15:18:34 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:18:34 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:18:34 rivoplus volumio[3447]: info: Discovery: Getting this device information May 26 15:18:34 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:34 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:18:37 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 15:18:37 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4327 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4325 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4329 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4331 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4328 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4332 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4326 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4323 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4334 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4321 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4335 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4324 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4330 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4322 May 26 15:18:37 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4333 May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4327 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4325 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4329 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4331 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4328 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4332 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4326 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4323 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4334 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4321 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4335 in service upnp_browser May 26 15:18:37 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4324 in service upnp_browser May 26 15:18:38 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4330 in service upnp_browser May 26 15:18:38 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4322 in service upnp_browser May 26 15:18:38 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@4333 in service upnp_browser May 26 15:18:52 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 26 15:18:52 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:52 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8706 May 26 15:18:52 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8704 May 26 15:18:52 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8707 May 26 15:18:52 rivoplus volumio[3447]: info: Preloading song: upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8705 May 26 15:18:52 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8706 in service upnp_browser May 26 15:18:52 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8704 in service upnp_browser May 26 15:18:52 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8707 in service upnp_browser May 26 15:18:52 rivoplus volumio[3447]: info: Exploding uri upnp/http://192.168.1.250:50001/ContentDirectory/control@22$@8705 in service upnp_browser May 26 15:18:55 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioReplaceandPlayItems May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::ClearQueue May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::updateTrackBlock May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrackBlock May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::stPlaybackTimer May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::serviceStop May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::serviceStop May 26 15:18:55 rivoplus volumio[3447]: info: [1748247535545] ControllerUPNPBrowser::stop May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::clearPlayQueue May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::saveQueue May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushQueue May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::addQueueItems May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::addQueueItems May 26 15:18:55 rivoplus volumio[3447]: info: Preload queue cleared May 26 15:18:55 rivoplus volumio[3447]: info: Adding Item to queue: upnp/folder/http://192.168.1.250:50001/ContentDirectory/control@22$971 May 26 15:18:55 rivoplus volumio[3447]: info: Exploding uri upnp/folder/http://192.168.1.250:50001/ContentDirectory/control@22$971 in service upnp_browser May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushQueue May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::saveQueue May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::updateTrackBlock May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrackBlock May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPlay May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::play index 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::play index undefined May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::startPlaybackTimer May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: [1748247535621] ControllerUPNPBrowser::clearAddPlayTrack May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand stop May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand stop took 79 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand stop took 4 milliseconds May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand clear May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand status took 10 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand clear took 10 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand status took 9 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand status took 8 milliseconds May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:50002/m/NDLNA/8706.wav" May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: 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 26 15:18:55 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: No code May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: 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 26 15:18:55 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus stop May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: No code May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:55 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:55 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:55 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 84ms May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 84ms May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: error: updateQueue error: null May 26 15:18:55 rivoplus volumio[3447]: error: updateQueue error: null May 26 15:18:55 rivoplus volumio[3447]: error: updateQueue error: null May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 91ms May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 87 milliseconds May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/8706.wav" May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 10ms May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 8ms May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:55 rivoplus volumio[3447]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 104ms May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand add "http://192.168.1.250:50002/m/NDLNA/8706.wav" took 56 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: CoreStateMachine::setConsumeUpdateService mpd May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand play May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces system playlist update May 26 15:18:55 rivoplus volumio[3447]: info: Ignoring MPD Status Update May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 16ms May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand play took 14 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 5ms May 26 15:18:55 rivoplus volumio[3447]: info: ------------------------------ 4ms May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand status took 84 milliseconds May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: May 26 15:18:55 rivoplus volumio[3447]: ---------------------------- MPD announces state update: player May 26 15:18:55 rivoplus volumio[3447]: info: ControllerMpd::getState May 26 15:18:55 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand status May 26 15:18:55 rivoplus volumio[3447]: info: sendMpdCommand status took 90 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand status took 95 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand status took 26 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 27 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand status took 41 milliseconds May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus stop May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 143ms May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand status took 51 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 12 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 11 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 12 milliseconds May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 10 milliseconds May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseState May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus play May 26 15:18:56 rivoplus volumio[3447]: info: Received an update from plugin. extracting info from payload May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus play May 26 15:18:56 rivoplus volumio[3447]: info: Received an update from plugin. extracting info from payload May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":13,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus play May 26 15:18:56 rivoplus volumio[3447]: info: Received an update from plugin. extracting info from payload May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":97,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus play May 26 15:18:56 rivoplus volumio[3447]: info: Received an update from plugin. extracting info from payload May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 270ms May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 266ms May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 190ms May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 186ms May 26 15:18:56 rivoplus volumio[3447]: info: sendMpdCommand playlistinfo took 131 milliseconds May 26 15:18:56 rivoplus volumio[3447]: verbose: ControllerMpd::parseTrackInfo May 26 15:18:56 rivoplus volumio[3447]: info: ControllerMpd::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::servicePushState May 26 15:18:56 rivoplus volumio[3447]: info: CorePlayQueue::getTrack 0 May 26 15:18:56 rivoplus volumio[3447]: verbose: STATE SERVICE {"status":"play","position":0,"seek":157,"duration":269,"samplerate":"192 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"8706.wav","artist":null,"album":null,"uri":"http://192.168.1.250:50002/m/NDLNA/8706.wav","trackType":"wav"} May 26 15:18:56 rivoplus volumio[3447]: verbose: CURRENT POSITION 0 May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState stateService play May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::syncState currentStatus play May 26 15:18:56 rivoplus volumio[3447]: info: Received an update from plugin. extracting info from payload May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: CoreStateMachine::pushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioPushState May 26 15:18:56 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output update for this device May 26 15:18:56 rivoplus volumio[3447]: info: MRS: Pushing multiroomSync output May 26 15:18:56 rivoplus volumio[3447]: info: ------------------------------ 241ms May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Signalling Playback active due to playback status change May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: Updating RAAT Signal Path May 26 15:18:56 rivoplus volumio[3447]: info: MCU Signalled Playback Active May 26 15:18:58 rivoplus volumio[3447]: info: Executing endpoint metavolumio May 26 15:18:58 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 26 15:19:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 26 15:19:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 26 15:19:04 rivoplus volumio[3447]: info: Discovery: Getting this device information May 26 15:19:04 rivoplus volumio[3447]: info: CoreCommandRouter::volumioGetState May 26 15:19:04 rivoplus volumio[3447]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 26 15:19:08 rivoplus ntpd[3427]: Soliciting pool server 103.184.124.254 May 26 15:19:13 rivoplus ntpd[3427]: Soliciting pool server 103.70.115.65 May 26 15:19:14 rivoplus ntpd[3427]: Soliciting pool server 2401:5f80:5001:1f::31f May 26 15:19:24 rivoplus volumio[3447]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:19:24 rivoplus volumio[3447]: Error: connect ETIMEDOUT 151.101.66.79:443 May 26 15:19:24 rivoplus volumio[3447]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 26 15:19:24 rivoplus volumio[3447]: errno: -110, May 26 15:19:24 rivoplus volumio[3447]: code: 'ETIMEDOUT', May 26 15:19:24 rivoplus volumio[3447]: syscall: 'connect', May 26 15:19:24 rivoplus volumio[3447]: address: '151.101.66.79', May 26 15:19:24 rivoplus volumio[3447]: port: 443 May 26 15:19:24 rivoplus volumio[3447]: } May 26 15:19:24 rivoplus volumio[3447]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 15:19:25 rivoplus sudo[9646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-26 15:18 May 26 15:19:25 rivoplus sudo[9646]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:04:38 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="0064c1a7ac4363e567dacab062cb5da8"