-- Logs begin at Sat 2025-05-17 14:23:50 CEST, end at Sat 2025-05-17 15:04:10 CEST. -- May 17 15:03:03 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:08 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:12 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:15 rivo volumio[3218]: info: CALLMETHOD: music_service tidal saveConfig [object Object] May 17 15:03:15 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig May 17 15:03:15 rivo volumio[3218]: info: Saving configuration May 17 15:03:15 rivo volumio[3218]: info: Done May 17 15:03:15 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:20 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:22 rivo volumio[3218]: info: CALLMETHOD: music_service tidal saveConfig [object Object] May 17 15:03:22 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig May 17 15:03:22 rivo volumio[3218]: info: Saving configuration May 17 15:03:22 rivo volumio[3218]: info: Done May 17 15:03:23 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:03:23 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:03:23 rivo volumio[3218]: info: Discovery: Getting this device information May 17 15:03:23 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:23 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:03:24 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:27 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:32 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:35 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:39 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:43 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:48 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:52 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 15:03:53 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 17 15:03:53 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 17 15:03:53 rivo volumio[3218]: info: Discovery: Getting this device information May 17 15:03:53 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:53 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 17 15:03:54 rivo sudo[18760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 15:03:54 rivo sudo[18760]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:03:54 rivo sudo[18760]: pam_unix(sudo:session): session closed for user root May 17 15:03:54 rivo sudo[18763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 15:03:54 rivo sudo[18763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:03:54 rivo sudo[18763]: pam_unix(sudo:session): session closed for user root May 17 15:03:54 rivo volumio[3218]: verbose: New Socket.io Connection to 192.168.1.57 from 192.168.1.101 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 17 15:03:54 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:03:54 rivo volumio[3218]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 17 15:03:54 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:54 rivo volumio[3218]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:03:54 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:03:54 rivo volumio[3218]: info: CoreCommandRouter::volumioGetQueue May 17 15:03:54 rivo volumio[3218]: info: CoreStateMachine::getQueue May 17 15:03:54 rivo volumio[3218]: info: CorePlayQueue::getQueue May 17 15:03:54 rivo volumio[3218]: info: Listing playlists May 17 15:03:56 rivo sudo[18768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 17 15:03:56 rivo sudo[18768]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:03:56 rivo sudo[18768]: pam_unix(sudo:session): session closed for user root May 17 15:03:56 rivo sudo[18772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 17 15:03:56 rivo sudo[18772]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 15:03:56 rivo sudo[18772]: pam_unix(sudo:session): session closed for user root May 17 15:03:56 rivo volumio[3218]: verbose: New Socket.io Connection to 192.168.1.57 from 192.168.1.101 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 May 17 15:03:56 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 17 15:03:56 rivo volumio[3218]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 17 15:03:56 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:56 rivo volumio[3218]: info: CoreCommandRouter::volumioGetVisibleSources May 17 15:03:56 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 17 15:03:56 rivo volumio[3218]: info: CoreCommandRouter::volumioGetQueue May 17 15:03:56 rivo volumio[3218]: info: CoreStateMachine::getQueue May 17 15:03:56 rivo volumio[3218]: info: CorePlayQueue::getQueue May 17 15:03:56 rivo volumio[3218]: info: Listing playlists May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::servicePause May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePause May 17 15:03:57 rivo volumio[3218]: info: [1747487037741] ControllerTidal::pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pause May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand pause May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand pause took 3 milliseconds May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: May 17 15:03:57 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 10 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 8 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 8 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 6 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 5 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand status took 4 milliseconds May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:57 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:57 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:57 rivo volumio[3218]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":154827,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:57 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState stateService pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:57 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:57 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:57 rivo volumio[3218]: info: CoreStateMachine::stPlaybackTimer May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 88ms May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 87ms May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 87ms May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 86ms May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 85ms May 17 15:03:57 rivo volumio[3218]: info: ------------------------------ 85ms May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:57 rivo volumio[3218]: info: MCU Signalled Playback Inactive May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPlay May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::play index undefined May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::startPlaybackTimer May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: info: [1747487038508] ControllerTidal::resume May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::resume May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand play May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand play took 15 milliseconds May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: May 17 15:03:58 rivo volumio[3218]: ---------------------------- MPD announces state update: player May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::getState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand status May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 10 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 10 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 9 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 7 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 5 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand status took 4 milliseconds May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseState May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 4 milliseconds May 17 15:03:58 rivo volumio[3218]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: verbose: ControllerMpd::parseTrackInfo May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus pause May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus play May 17 15:03:58 rivo volumio[3218]: info: Received an update from plugin. extracting info from payload May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus play May 17 15:03:58 rivo volumio[3218]: info: Received an update from plugin. extracting info from payload May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus play May 17 15:03:58 rivo volumio[3218]: info: Received an update from plugin. extracting info from payload May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus play May 17 15:03:58 rivo volumio[3218]: info: Received an update from plugin. extracting info from payload May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ControllerMpd::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::servicePushState May 17 15:03:58 rivo volumio[3218]: info: CorePlayQueue::getTrack 0 May 17 15:03:58 rivo volumio[3218]: verbose: STATE SERVICE {"status":"play","position":0,"seek":156063,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"794 Kbps","isStreaming":false,"title":"215150707&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/215150707&soundQuality=HI_RES","trackType":"tidal"} May 17 15:03:58 rivo volumio[3218]: verbose: CURRENT POSITION 0 May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState stateService play May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::syncState currentStatus play May 17 15:03:58 rivo volumio[3218]: info: Received an update from plugin. extracting info from payload May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: CoreStateMachine::pushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioPushState May 17 15:03:58 rivo volumio[3218]: info: CoreCommandRouter::volumioGetState May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output update for this device May 17 15:03:58 rivo volumio[3218]: info: MRS: Pushing multiroomSync output May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 112ms May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 112ms May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 113ms May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 112ms May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 112ms May 17 15:03:58 rivo volumio[3218]: info: ------------------------------ 111ms May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Signalling Playback active due to playback status change May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: Updating RAAT Signal Path May 17 15:03:58 rivo volumio[3218]: info: MCU Signalled Playback Active May 17 15:04:09 rivo volumio[3218]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay May 17 15:04:09 rivo volumio[3218]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom May 17 15:04:09 rivo volumio[3218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 15:04:09 rivo volumio[3218]: TypeError: Cannot read property 'then' of undefined May 17 15:04:09 rivo volumio[3218]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) May 17 15:04:09 rivo volumio[3218]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) May 17 15:04:09 rivo volumio[3218]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) May 17 15:04:09 rivo volumio[3218]: at Socket.emit (events.js:400:28) May 17 15:04:09 rivo volumio[3218]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 17 15:04:09 rivo volumio[3218]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 17 15:04:09 rivo volumio[3218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 15:04:10 rivo sudo[18805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 15:03 May 17 15:04:10 rivo sudo[18805]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 06:24:39 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c8a59422b1bfedffc72f72cef58ad20b"