-- Logs begin at Wed 2025-03-12 15:48:57 +05, end at Thu 2025-03-13 16:48:51 +05. -- Mar 13 16:47:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:10 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:10 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:25 volumio-rivo volumio[3272]: info: Removing uri qobuz://album/q5bngbde20wdc from favourites Mar 13 16:47:25 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , removeFromFavourites Mar 13 16:47:31 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:31 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:33 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:33 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:33 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:40 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:40 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:43 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:43 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:45 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:45 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:45 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:45 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:45 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:45 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:47 volumio-rivo sudo[10662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 13 16:47:47 volumio-rivo sudo[10662]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:47:47 volumio-rivo sudo[10662]: pam_unix(sudo:session): session closed for user root Mar 13 16:47:47 volumio-rivo sudo[10665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 13 16:47:47 volumio-rivo sudo[10665]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:47:47 volumio-rivo sudo[10665]: pam_unix(sudo:session): session closed for user root Mar 13 16:47:47 volumio-rivo volumio[3272]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.146 UA: Mozilla/5.0 (Linux; Android 14; SM-X516B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.39 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 13 16:47:47 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 13 16:47:48 volumio-rivo sudo[10672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 13 16:47:48 volumio-rivo sudo[10672]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:47:48 volumio-rivo volumio[3272]: error: Failed request for metavolumio API Mar 13 16:47:48 volumio-rivo sudo[10672]: pam_unix(sudo:session): session closed for user root Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 13 16:47:48 volumio-rivo sudo[10675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 13 16:47:48 volumio-rivo sudo[10675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 13 16:47:48 volumio-rivo sudo[10675]: pam_unix(sudo:session): session closed for user root Mar 13 16:47:48 volumio-rivo volumio[3272]: verbose: New Socket.io Connection to 192.168.0.128 from 192.168.0.146 UA: Mozilla/5.0 (Linux; Android 14; SM-X516B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/134.0.6998.39 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetVisibleSources Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 13 16:47:48 volumio-rivo volumio[3272]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 13 16:47:48 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:48 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:48 volumio-rivo volumio[3272]: info: Listing playlists Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 16:47:48 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 13 16:47:49 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:49 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:49 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 16:47:50 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:50 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetQueue Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CoreStateMachine::getQueue Mar 13 16:47:50 volumio-rivo volumio[3272]: info: CorePlayQueue::getQueue Mar 13 16:47:51 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:51 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:51 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:51 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:51 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:51 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 16:47:53 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:53 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 16:47:53 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:53 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:53 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioClearQueue Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::ClearQueue Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::stPlaybackTimer Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::updateTrackBlock Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrackBlock Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::serviceStop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::serviceStop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: [1741866474798] ControllerQobuz::stop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::stop Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::clearPlayQueue Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:47:54 volumio-rivo volumio[3272]: info: Mar 13 16:47:54 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand stop took 100 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:47:54 volumio-rivo volumio[3272]: info: Mar 13 16:47:54 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:47:54 volumio-rivo volumio[3272]: info: Mar 13 16:47:54 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand status took 30 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand status took 17 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand status took 14 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:54 volumio-rivo volumio[3272]: 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":"181284815","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/181284815","trackType":"qobuz"} Mar 13 16:47:54 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 2 Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:54 volumio-rivo volumio[3272]: info: No code Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:54 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:54 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:55 volumio-rivo volumio[3272]: 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":"181284815","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/181284815","trackType":"qobuz"} Mar 13 16:47:55 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 2 Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: No code Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:47:55 volumio-rivo volumio[3272]: 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":"181284815","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/181284815","trackType":"qobuz"} Mar 13 16:47:55 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 2 Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: No code Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:55 volumio-rivo volumio[3272]: info: ------------------------------ 281ms Mar 13 16:47:55 volumio-rivo volumio[3272]: info: ------------------------------ 279ms Mar 13 16:47:55 volumio-rivo volumio[3272]: info: ------------------------------ 267ms Mar 13 16:47:55 volumio-rivo volumio[3272]: info: Signalling Playback active due to playback status change Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MCU Signalled Playback Inactive Mar 13 16:47:55 volumio-rivo volumio[3272]: info: MCU Signalled Playback Active Mar 13 16:47:55 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 13 16:47:56 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:47:56 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:47:56 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:47:56 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:47:56 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:56 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:47:57 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioClearQueue Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreStateMachine::ClearQueue Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CorePlayQueue::clearPlayQueue Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:47:57 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:47:57 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:47:57 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:47:57 volumio-rivo volumio[3272]: info: MCU Signalled Playback Inactive Mar 13 16:47:58 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 13 16:47:59 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:47:59 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 13 16:48:08 volumio-rivo volumio[3272]: info: Received Get System Version Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 13 16:48:08 volumio-rivo volumio[3272]: info: Received Get System Info Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:48:08 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:48:08 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:48:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:48:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:48:10 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:48:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:48:10 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 2 Mar 13 16:48:10 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:48:16 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 13 16:48:17 volumio-rivo volumio[3272]: info: handleBrowseUri took 447 milliseconds Mar 13 16:48:17 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:17 volumio-rivo volumio[3272]: info: Executing endpoint getSimilarAlbums Mar 13 16:48:17 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 13 16:48:17 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:17 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:17 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:17 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:18 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreStateMachine::ClearQueue Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CorePlayQueue::clearPlayQueue Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CoreStateMachine::addQueueItems Mar 13 16:48:18 volumio-rivo volumio[3272]: info: CorePlayQueue::addQueueItems Mar 13 16:48:18 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:18 volumio-rivo volumio[3272]: info: Adding Item to queue: qobuz://album/e8t0le9y1rqrc Mar 13 16:48:18 volumio-rivo volumio[3272]: info: Exploding uri qobuz://album/e8t0le9y1rqrc in service qobuz Mar 13 16:48:18 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:19 volumio-rivo volumio[3272]: info: explodeUri took 580 milliseconds Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::updateTrackBlock Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrackBlock Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPlay Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::play index 0 Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::play index undefined Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreStateMachine::startPlaybackTimer Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:19 volumio-rivo volumio[3272]: info: [1741866499230] ControllerQobuz::clearAddPlayTrack Mar 13 16:48:19 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 16:48:19 volumio-rivo volumio[3272]: info: sendMpdCommand stop took 6 milliseconds Mar 13 16:48:19 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Mar 13 16:48:19 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: sendMpdCommand clear took 2 milliseconds Mar 13 16:48:19 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/173074369" Mar 13 16:48:19 volumio-rivo volumio[3272]: STREAMING PROXY: Handling url /?data=qobuz://song/173074369 Mar 13 16:48:19 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:19 volumio-rivo volumio[3272]: info: ------------------------------ 7ms Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Mar 13 16:48:19 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Mar 13 16:48:19 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:19 volumio-rivo volumio[3272]: info: Executing endpoint getStreamUrlqobuz Mar 13 16:48:19 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 13 16:48:19 volumio-rivo volumio[3272]: info: getStreamUrl took 573 milliseconds Mar 13 16:48:19 volumio-rivo volumio[3272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2631061&eid=173074369&fmt=7&profile=raw&app_id=539451548&cid=2630226&etsp=1741870099&hmac=3B71Nvys611lQ9Ai5iEaR_61y90 Mar 13 16:48:20 volumio-rivo volumio[3272]: STREAMING PROXY: Response: 200, length: 23667440 Mar 13 16:48:20 volumio-rivo volumio[3272]: STREAMING PROXY: Client dropped request, destroying Mar 13 16:48:20 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/173074369" Mar 13 16:48:20 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:20 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Mar 13 16:48:20 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:20 volumio-rivo volumio[3272]: info: ------------------------------ 1409ms Mar 13 16:48:20 volumio-rivo volumio[3272]: info: ------------------------------ 1405ms Mar 13 16:48:20 volumio-rivo volumio[3272]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/173074369" took 5 milliseconds Mar 13 16:48:20 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 16:48:20 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand play Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Mar 13 16:48:20 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Mar 13 16:48:20 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:20 volumio-rivo volumio[3272]: STREAMING PROXY: Handling url /?data=qobuz://song/173074369 Mar 13 16:48:20 volumio-rivo volumio[3272]: info: ------------------------------ 12ms Mar 13 16:48:20 volumio-rivo volumio[3272]: info: sendMpdCommand play took 7 milliseconds Mar 13 16:48:20 volumio-rivo volumio[3272]: info: ------------------------------ 6ms Mar 13 16:48:20 volumio-rivo volumio[3272]: info: ------------------------------ 5ms Mar 13 16:48:20 volumio-rivo volumio[3272]: info: Executing endpoint getStreamUrlqobuz Mar 13 16:48:20 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 13 16:48:21 volumio-rivo volumio[3272]: info: getStreamUrl took 538 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2631061&eid=173074369&fmt=7&profile=raw&app_id=539451548&cid=2630226&etsp=1741870100&hmac=JMDJnKLhVeFfGIyB7gGNgjK1KvQ Mar 13 16:48:21 volumio-rivo volumio[3272]: STREAMING PROXY: Response: 200, length: 23667440 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 39 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 45 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 45 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 9 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 87ms Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Mar 13 16:48:21 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 40 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 37 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 13 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand status took 10 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1472 Kbps","isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":720,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1472 Kbps","isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1211,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1462 Kbps","isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 335ms Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 336ms Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 305ms Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 244 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 245 milliseconds Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1211,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1462 Kbps","isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1211,"duration":136,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1462 Kbps","isStreaming":false,"title":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:21 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:21 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:21 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 559ms Mar 13 16:48:21 volumio-rivo volumio[3272]: info: ------------------------------ 558ms Mar 13 16:48:22 volumio-rivo volumio[3272]: info: Signalling Playback active due to playback status change Mar 13 16:48:22 volumio-rivo volumio[3272]: info: MCU Signalled Playback Active Mar 13 16:48:23 volumio-rivo volumio[3272]: STREAMING PROXY: Client dropped request, destroying Mar 13 16:48:23 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:23 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:23 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:23 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:23 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:23 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:24 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:28 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: handleBrowseUri took 572 milliseconds Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880042 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880043 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880044 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880045 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880046 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880047 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880048 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880049 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880050 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Preloading song: qobuz://song/261880051 Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880042 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880043 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Executing endpoint getSimilarAlbums Mar 13 16:48:29 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880044 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880045 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:29 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:29 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880046 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880047 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880048 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880049 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880050 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:29 volumio-rivo volumio[3272]: info: Exploding uri qobuz://song/261880051 in service qobuz Mar 13 16:48:29 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 510 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 597 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 542 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 530 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 566 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 508 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 596 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 512 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 589 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: explodeUri took 564 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::ClearQueue Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::stPlaybackTimer Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::updateTrackBlock Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrackBlock Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::serviceStop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::serviceStop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: [1741866510778] ControllerQobuz::stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::stop Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::clearPlayQueue Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::addQueueItems Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::addQueueItems Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Preload queue cleared Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Adding Item to queue: qobuz://album/lsw9qvdbt682a Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Exploding uri qobuz://album/lsw9qvdbt682a in service qobuz Mar 13 16:48:30 volumio-rivo volumio[3272]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Mar 13 16:48:30 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand stop took 77 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Mar 13 16:48:30 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:30 volumio-rivo volumio[3272]: info: Mar 13 16:48:30 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand status took 19 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand status took 17 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand status took 16 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: 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":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: No code Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: 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":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: No code Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: 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":"173074369","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/173074369","trackType":"qobuz"} Mar 13 16:48:30 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:30 volumio-rivo volumio[3272]: info: No code Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:30 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:31 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:31 volumio-rivo volumio[3272]: info: ------------------------------ 246ms Mar 13 16:48:31 volumio-rivo volumio[3272]: info: ------------------------------ 244ms Mar 13 16:48:31 volumio-rivo volumio[3272]: info: ------------------------------ 244ms Mar 13 16:48:31 volumio-rivo volumio[3272]: info: MCU Signalled Playback Inactive Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Signalling Playback active due to playback status change Mar 13 16:48:31 volumio-rivo volumio[3272]: info: MCU Signalled Playback Active Mar 13 16:48:31 volumio-rivo volumio[3272]: info: explodeUri took 724 milliseconds Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushQueue Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CorePlayQueue::saveQueue Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::updateTrackBlock Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrackBlock Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPlay Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::play index 0 Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::stop Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::play index undefined Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreStateMachine::startPlaybackTimer Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:31 volumio-rivo volumio[3272]: info: [1741866511529] ControllerQobuz::clearAddPlayTrack Mar 13 16:48:31 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand stop Mar 13 16:48:31 volumio-rivo volumio[3272]: info: sendMpdCommand stop took 7 milliseconds Mar 13 16:48:31 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand clear Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Mar 13 16:48:31 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:31 volumio-rivo volumio[3272]: info: sendMpdCommand clear took 6 milliseconds Mar 13 16:48:31 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand load "http://127.0.0.1:3245?data=qobuz://song/261880042" Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Mar 13 16:48:31 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Mar 13 16:48:31 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:31 volumio-rivo volumio[3272]: STREAMING PROXY: Handling url /?data=qobuz://song/261880042 Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:31 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:31 volumio-rivo volumio[3272]: info: ------------------------------ 8ms Mar 13 16:48:31 volumio-rivo volumio[3272]: info: Executing endpoint getStreamUrlqobuz Mar 13 16:48:31 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 13 16:48:32 volumio-rivo volumio[3272]: info: getStreamUrl took 476 milliseconds Mar 13 16:48:32 volumio-rivo volumio[3272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2631061&eid=261880042&fmt=7&profile=raw&app_id=539451548&cid=2630226&etsp=1741870111&hmac=t-hrDhkNuL8fnqYZmI04XhQgWio Mar 13 16:48:32 volumio-rivo volumio[3272]: STREAMING PROXY: Response: 200, length: 44517524 Mar 13 16:48:32 volumio-rivo volumio[3272]: STREAMING PROXY: Client dropped request, destroying Mar 13 16:48:32 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:32 volumio-rivo volumio[3272]: error: updateQueue error: null Mar 13 16:48:32 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/261880042" Mar 13 16:48:32 volumio-rivo volumio[3272]: info: ------------------------------ 1365ms Mar 13 16:48:32 volumio-rivo volumio[3272]: info: ------------------------------ 1363ms Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Mar 13 16:48:32 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:32 volumio-rivo volumio[3272]: info: sendMpdCommand add "http://127.0.0.1:3245?data=qobuz://song/261880042" took 3 milliseconds Mar 13 16:48:32 volumio-rivo volumio[3272]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 13 16:48:32 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand play Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Mar 13 16:48:32 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Mar 13 16:48:32 volumio-rivo volumio[3272]: ---------------------------- MPD announces system playlist update Mar 13 16:48:32 volumio-rivo volumio[3272]: STREAMING PROXY: Handling url /?data=qobuz://song/261880042 Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Ignoring MPD Status Update Mar 13 16:48:32 volumio-rivo volumio[3272]: info: ------------------------------ 8ms Mar 13 16:48:32 volumio-rivo volumio[3272]: info: sendMpdCommand play took 6 milliseconds Mar 13 16:48:32 volumio-rivo volumio[3272]: info: ------------------------------ 6ms Mar 13 16:48:32 volumio-rivo volumio[3272]: info: ------------------------------ 4ms Mar 13 16:48:32 volumio-rivo volumio[3272]: info: Executing endpoint getStreamUrlqobuz Mar 13 16:48:32 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: qobuz , getStreamUrlAPI Mar 13 16:48:33 volumio-rivo volumio[3272]: info: getStreamUrl took 520 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: STREAMING PROXY: Got real url: https://streaming-qobuz-std.akamaized.net/file?uid=2631061&eid=261880042&fmt=7&profile=raw&app_id=539451548&cid=2630226&etsp=1741870112&hmac=oZobMSQ4mczZnWjGqOT4A75QasI Mar 13 16:48:33 volumio-rivo volumio[3272]: STREAMING PROXY: Response: 200, length: 44517524 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 80 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 99 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 97 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 18 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus stop Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 118ms Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Mar 13 16:48:33 volumio-rivo volumio[3272]: ---------------------------- MPD announces state update: player Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::getState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand status Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 11 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand status took 7 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseState Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":147,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1195 Kbps","isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":378,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1441 Kbps","isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1404 Kbps","isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 296ms Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 295ms Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 225ms Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 170 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: info: sendMpdCommand playlistinfo took 168 milliseconds Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: ControllerMpd::parseTrackInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1404 Kbps","isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ControllerMpd::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::servicePushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CorePlayQueue::getTrack 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1238,"duration":237,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1404 Kbps","isStreaming":false,"title":"261880042","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=qobuz://song/261880042","trackType":"qobuz"} Mar 13 16:48:33 volumio-rivo volumio[3272]: verbose: CURRENT POSITION 0 Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState stateService play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::syncState currentStatus play Mar 13 16:48:33 volumio-rivo volumio[3272]: info: Received an update from plugin. extracting info from payload Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreStateMachine::pushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioPushState Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output update for this device Mar 13 16:48:33 volumio-rivo volumio[3272]: info: MRS: Pushing multiroomSync output Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 345ms Mar 13 16:48:33 volumio-rivo volumio[3272]: info: ------------------------------ 343ms Mar 13 16:48:35 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:35 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:35 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:35 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:35 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:35 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: Executing endpoint metavolumio Mar 13 16:48:36 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 13 16:48:37 volumio-rivo volumio[3272]: STREAMING PROXY: Client dropped request, destroying Mar 13 16:48:37 volumio-rivo volumio[3272]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eiv%C3%B8r/Room/4fc399a4-caf4-4fb0-b781-af8601395499.png' Mar 13 16:48:37 volumio-rivo volumio[3272]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eiv%C3%B8r/Sl%C3%B8r/911779dd-ec09-4238-985e-4f8fcc569ad5.jpg' Mar 13 16:48:38 volumio-rivo volumio[3272]: error: Failed request for metavolumio API Mar 13 16:48:39 volumio-rivo volumio[3272]: error: Failed request for metavolumio API Mar 13 16:48:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 13 16:48:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 13 16:48:40 volumio-rivo volumio[3272]: info: Discovery: Getting this device information Mar 13 16:48:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::volumioGetState Mar 13 16:48:40 volumio-rivo volumio[3272]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 13 16:48:51 volumio-rivo volumio[3272]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 16:48:51 volumio-rivo volumio[3272]: Error: connect ETIMEDOUT 151.101.38.79:443 Mar 13 16:48:51 volumio-rivo volumio[3272]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 13 16:48:51 volumio-rivo volumio[3272]: errno: -110, Mar 13 16:48:51 volumio-rivo volumio[3272]: code: 'ETIMEDOUT', Mar 13 16:48:51 volumio-rivo volumio[3272]: syscall: 'connect', Mar 13 16:48:51 volumio-rivo volumio[3272]: address: '151.101.38.79', Mar 13 16:48:51 volumio-rivo volumio[3272]: port: 443 Mar 13 16:48:51 volumio-rivo volumio[3272]: } Mar 13 16:48:51 volumio-rivo volumio[3272]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 13 16:48:51 volumio-rivo sudo[10990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-13 16:47 Mar 13 16:48:51 volumio-rivo sudo[10990]: 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="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:49:04 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="65eb03fcbe62db7e7fbed9cd39ac44df"