-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-01-17 19:12:13 CET. -- Jan 17 19:11:00 volumio volumio[1001]: info: browseTIDALUri took 881 milliseconds Jan 17 19:11:00 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:10 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:10 volumio volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::ClearQueue Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:10 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::addQueueItems Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::addQueueItems Jan 17 19:11:10 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:10 volumio volumio[1001]: info: Adding Item to queue: tidal://new/albums/recommended/318877364 Jan 17 19:11:10 volumio volumio[1001]: info: Exploding uri tidal://new/albums/recommended/318877364 in service tidal Jan 17 19:11:10 volumio volumio[1001]: info: explodeTIDALUri took 287 milliseconds Jan 17 19:11:10 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Jan 17 19:11:10 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::play index 0 Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:10 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:10 volumio volumio[1001]: info: [1737137470702] ControllerTidal::clearAddPlayTrack Jan 17 19:11:10 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:10 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:10 volumio volumio[1001]: info: sendMpdCommand stop took 7 milliseconds Jan 17 19:11:10 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:10 volumio volumio[1001]: info: Jan 17 19:11:10 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:10 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:10 volumio volumio[1001]: info: sendMpdCommand clear took 12 milliseconds Jan 17 19:11:10 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877365&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:10 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:10 volumio volumio[1001]: info: Jan 17 19:11:10 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:10 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:10 volumio volumio[1001]: info: ------------------------------ 7ms Jan 17 19:11:10 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877365&soundQuality=HIGHEST_POSSIBLE" took 4 milliseconds Jan 17 19:11:10 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:10 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:10 volumio volumio[1001]: info: ------------------------------ 7ms Jan 17 19:11:10 volumio volumio[1001]: info: sendMpdCommand play took 4 milliseconds Jan 17 19:11:10 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877365&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:10 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:10 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:11 volumio volumio[1001]: info: getStreamUrl took 917 milliseconds Jan 17 19:11:11 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:11 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:11 volumio volumio[1001]: info: Jan 17 19:11:11 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:11 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:11 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:11 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:11 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:11 volumio volumio[1001]: info: sendMpdCommand status took 13 milliseconds Jan 17 19:11:11 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:11 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:11 volumio volumio[1001]: info: sendMpdCommand clearerror took 6 milliseconds Jan 17 19:11:11 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:11 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:11 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:11 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:11 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:11 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:11 volumio volumio[1001]: 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":"318877365&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877365&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:11 volumio volumio[1001]: verbose: CURRENT POSITION 0 Jan 17 19:11:11 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:11 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:11 volumio volumio[1001]: info: No code Jan 17 19:11:11 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:11 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:11 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:11 volumio volumio[1001]: info: ------------------------------ 139ms Jan 17 19:11:11 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:11 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:12 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:12 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::play index 1 Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:12 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:12 volumio volumio[1001]: info: [1737137472809] ControllerTidal::clearAddPlayTrack Jan 17 19:11:12 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:12 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:12 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:12 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:12 volumio volumio[1001]: info: Jan 17 19:11:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:12 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:12 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:12 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877366&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:12 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:12 volumio volumio[1001]: info: Jan 17 19:11:12 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:12 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:12 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:12 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877366&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:12 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:12 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:12 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:12 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:12 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877366&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:12 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:12 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:13 volumio volumio[1001]: info: getStreamUrl took 364 milliseconds Jan 17 19:11:13 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:13 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:13 volumio volumio[1001]: info: Jan 17 19:11:13 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:13 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:13 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:13 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:13 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:13 volumio volumio[1001]: info: sendMpdCommand status took 11 milliseconds Jan 17 19:11:13 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:13 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:13 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:13 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 17 19:11:13 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:13 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:13 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:13 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:13 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:13 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:13 volumio volumio[1001]: 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":"318877366&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877366&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:13 volumio volumio[1001]: verbose: CURRENT POSITION 1 Jan 17 19:11:13 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:13 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:13 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:13 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:13 volumio volumio[1001]: info: No code Jan 17 19:11:13 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:13 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:13 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:13 volumio volumio[1001]: info: ------------------------------ 129ms Jan 17 19:11:13 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:13 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:14 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::play index 2 Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:14 volumio volumio[1001]: info: [1737137474223] ControllerTidal::clearAddPlayTrack Jan 17 19:11:14 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:14 volumio volumio[1001]: info: Jan 17 19:11:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:14 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877367&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:14 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:14 volumio volumio[1001]: info: Jan 17 19:11:14 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:14 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:14 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877367&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:14 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:14 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877367&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:14 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:14 volumio volumio[1001]: info: getStreamUrl took 500 milliseconds Jan 17 19:11:14 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:14 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:14 volumio volumio[1001]: info: Jan 17 19:11:14 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:14 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:14 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand status took 11 milliseconds Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:14 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 19:11:14 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:14 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:14 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:14 volumio volumio[1001]: 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":"318877367&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877367&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:14 volumio volumio[1001]: verbose: CURRENT POSITION 2 Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:14 volumio volumio[1001]: info: No code Jan 17 19:11:14 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:14 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:14 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:14 volumio volumio[1001]: info: ------------------------------ 150ms Jan 17 19:11:14 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:14 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:15 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:15 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::play index 3 Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:15 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:15 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:15 volumio volumio[1001]: info: [1737137475773] ControllerTidal::clearAddPlayTrack Jan 17 19:11:15 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:15 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:15 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:15 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:15 volumio volumio[1001]: info: Jan 17 19:11:15 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:15 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:15 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:15 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877368&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:15 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:15 volumio volumio[1001]: info: Jan 17 19:11:15 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:15 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:15 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:15 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877368&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:15 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:15 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:15 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:15 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:15 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877368&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:15 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:15 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:16 volumio volumio[1001]: info: getStreamUrl took 506 milliseconds Jan 17 19:11:16 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:16 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:16 volumio volumio[1001]: info: Jan 17 19:11:16 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:16 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:16 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:16 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:16 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:16 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Jan 17 19:11:16 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:16 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:16 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:16 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:16 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:16 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:16 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:16 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:16 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:16 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:16 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:16 volumio volumio[1001]: 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":"318877368&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877368&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:16 volumio volumio[1001]: verbose: CURRENT POSITION 3 Jan 17 19:11:16 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:16 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:16 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:16 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:16 volumio volumio[1001]: info: No code Jan 17 19:11:16 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:16 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:16 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:16 volumio volumio[1001]: info: ------------------------------ 123ms Jan 17 19:11:16 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:16 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:17 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:17 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::play index 4 Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:17 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:17 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:17 volumio volumio[1001]: info: [1737137477324] ControllerTidal::clearAddPlayTrack Jan 17 19:11:17 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:17 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:17 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:17 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:17 volumio volumio[1001]: info: Jan 17 19:11:17 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:17 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:17 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Jan 17 19:11:17 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877369&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:17 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:17 volumio volumio[1001]: info: Jan 17 19:11:17 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:17 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:17 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:17 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877369&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:17 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:17 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:17 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:17 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:17 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877369&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:17 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:17 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:18 volumio volumio[1001]: info: getStreamUrl took 955 milliseconds Jan 17 19:11:18 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:18 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:18 volumio volumio[1001]: info: Jan 17 19:11:18 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:18 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:18 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:18 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:18 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:18 volumio volumio[1001]: info: sendMpdCommand status took 12 milliseconds Jan 17 19:11:18 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:18 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:18 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:18 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 19:11:18 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:18 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:18 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:18 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:18 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:18 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:18 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:18 volumio volumio[1001]: 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":"318877369&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877369&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:18 volumio volumio[1001]: verbose: CURRENT POSITION 4 Jan 17 19:11:18 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:18 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:18 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:18 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:18 volumio volumio[1001]: info: No code Jan 17 19:11:18 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:18 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:18 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:18 volumio volumio[1001]: info: ------------------------------ 126ms Jan 17 19:11:18 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:18 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:19 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::play index 5 Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:19 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:19 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:19 volumio volumio[1001]: info: [1737137479328] ControllerTidal::clearAddPlayTrack Jan 17 19:11:19 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:19 volumio volumio[1001]: info: Jan 17 19:11:19 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:19 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877370&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:19 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:19 volumio volumio[1001]: info: Jan 17 19:11:19 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:19 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:19 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877370&soundQuality=HIGHEST_POSSIBLE" took 4 milliseconds Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:19 volumio volumio[1001]: info: ------------------------------ 5ms Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:19 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877370&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:19 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:19 volumio volumio[1001]: info: getStreamUrl took 292 milliseconds Jan 17 19:11:19 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:19 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:19 volumio volumio[1001]: info: Jan 17 19:11:19 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:19 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:19 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:19 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:19 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:19 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:19 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:19 volumio volumio[1001]: 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":"318877370&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877370&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:19 volumio volumio[1001]: verbose: CURRENT POSITION 5 Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:19 volumio volumio[1001]: info: No code Jan 17 19:11:19 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:19 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:19 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:19 volumio volumio[1001]: info: ------------------------------ 135ms Jan 17 19:11:19 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:19 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:20 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:20 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::play index 6 Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:20 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:20 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:11:20 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:11:20 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 17 19:11:20 volumio volumio[1001]: info: [1737137480669] ControllerTidal::clearAddPlayTrack Jan 17 19:11:20 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:20 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:20 volumio volumio[1001]: info: Jan 17 19:11:20 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:20 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:20 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877371&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:20 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:20 volumio volumio[1001]: info: Jan 17 19:11:20 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:20 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:20 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:20 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318877371&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:20 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:20 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:20 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:20 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:20 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/318877371&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:20 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:20 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:21 volumio volumio[1001]: info: getStreamUrl took 335 milliseconds Jan 17 19:11:21 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:21 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:21 volumio volumio[1001]: info: Jan 17 19:11:21 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:21 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:21 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:21 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:21 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:21 volumio volumio[1001]: info: sendMpdCommand status took 17 milliseconds Jan 17 19:11:21 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:21 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:21 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:21 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 19:11:21 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:21 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:21 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:21 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:21 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:21 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:21 volumio volumio[1001]: 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":"318877371&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318877371&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:21 volumio volumio[1001]: verbose: CURRENT POSITION 6 Jan 17 19:11:21 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:21 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:21 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:21 volumio volumio[1001]: info: No code Jan 17 19:11:21 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:21 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:21 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:21 volumio volumio[1001]: info: ------------------------------ 144ms Jan 17 19:11:21 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:21 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:22 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:22 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::play index 7 Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:22 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:22 volumio volumio[1001]: info: CorePlayQueue::getTrack 7 Jan 17 19:11:23 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:23 volumio volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::ClearQueue Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::addQueueItems Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::addQueueItems Jan 17 19:11:23 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:23 volumio volumio[1001]: info: Adding Item to queue: tidal://new/albums/recommended/298421280 Jan 17 19:11:23 volumio volumio[1001]: info: Exploding uri tidal://new/albums/recommended/298421280 in service tidal Jan 17 19:11:23 volumio volumio[1001]: info: explodeTIDALUri took 254 milliseconds Jan 17 19:11:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Jan 17 19:11:23 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::play index 0 Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:23 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:23 volumio volumio[1001]: info: [1737137483943] ControllerTidal::clearAddPlayTrack Jan 17 19:11:23 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:23 volumio volumio[1001]: info: sendMpdCommand stop took 9 milliseconds Jan 17 19:11:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:23 volumio volumio[1001]: info: Jan 17 19:11:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:23 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:23 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421285&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:23 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:23 volumio volumio[1001]: info: Jan 17 19:11:23 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:23 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:23 volumio volumio[1001]: info: ------------------------------ 5ms Jan 17 19:11:23 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421285&soundQuality=HIGHEST_POSSIBLE" took 4 milliseconds Jan 17 19:11:23 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:23 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:23 volumio volumio[1001]: info: ------------------------------ 5ms Jan 17 19:11:23 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:23 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421285&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:23 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:23 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:24 volumio volumio[1001]: info: getStreamUrl took 295 milliseconds Jan 17 19:11:24 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:24 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:24 volumio volumio[1001]: info: Jan 17 19:11:24 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:24 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:24 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:24 volumio volumio[1001]: info: sendMpdCommand status took 16 milliseconds Jan 17 19:11:24 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:24 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:24 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 19:11:24 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:24 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:24 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:24 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:24 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:24 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:24 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:24 volumio volumio[1001]: 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":"298421285&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421285&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:24 volumio volumio[1001]: verbose: CURRENT POSITION 0 Jan 17 19:11:24 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:24 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:24 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:24 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:24 volumio volumio[1001]: info: No code Jan 17 19:11:24 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:24 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:24 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:24 volumio volumio[1001]: info: ------------------------------ 169ms Jan 17 19:11:24 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:24 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:25 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::play index 1 Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:25 volumio volumio[1001]: info: [1737137485299] ControllerTidal::clearAddPlayTrack Jan 17 19:11:25 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:25 volumio volumio[1001]: info: Jan 17 19:11:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:25 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421286&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:25 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:25 volumio volumio[1001]: info: Jan 17 19:11:25 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:25 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:25 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421286&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:25 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:25 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421286&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:25 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:25 volumio volumio[1001]: info: getStreamUrl took 533 milliseconds Jan 17 19:11:25 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:25 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:25 volumio volumio[1001]: info: Jan 17 19:11:25 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:25 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:25 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand status took 11 milliseconds Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:25 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:25 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:25 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:25 volumio volumio[1001]: info: CorePlayQueue::getTrack 1 Jan 17 19:11:25 volumio volumio[1001]: 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":"298421286&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421286&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:25 volumio volumio[1001]: verbose: CURRENT POSITION 1 Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:25 volumio volumio[1001]: info: No code Jan 17 19:11:25 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:25 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:25 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:25 volumio volumio[1001]: info: ------------------------------ 137ms Jan 17 19:11:26 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:26 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:26 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:26 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::play index 2 Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:26 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:26 volumio volumio[1001]: info: [1737137486876] ControllerTidal::clearAddPlayTrack Jan 17 19:11:26 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:26 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:26 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:26 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:26 volumio volumio[1001]: info: Jan 17 19:11:26 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:26 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:26 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:26 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421287&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:26 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:26 volumio volumio[1001]: info: Jan 17 19:11:26 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:26 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:26 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:26 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421287&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:26 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:26 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:26 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:26 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:26 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421287&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:26 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:27 volumio volumio[1001]: info: getStreamUrl took 503 milliseconds Jan 17 19:11:27 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:27 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:27 volumio volumio[1001]: info: Jan 17 19:11:27 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:27 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:27 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:27 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:27 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:27 volumio volumio[1001]: info: sendMpdCommand status took 14 milliseconds Jan 17 19:11:27 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:27 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:27 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:27 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:27 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:27 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:27 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:27 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:27 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:27 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:27 volumio volumio[1001]: info: CorePlayQueue::getTrack 2 Jan 17 19:11:27 volumio volumio[1001]: 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":"298421287&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421287&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:27 volumio volumio[1001]: verbose: CURRENT POSITION 2 Jan 17 19:11:27 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:27 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:27 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:27 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:27 volumio volumio[1001]: info: No code Jan 17 19:11:27 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:27 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:27 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:27 volumio volumio[1001]: info: ------------------------------ 133ms Jan 17 19:11:27 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:27 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:28 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:28 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::play index 3 Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:28 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:28 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:28 volumio volumio[1001]: info: [1737137488435] ControllerTidal::clearAddPlayTrack Jan 17 19:11:28 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:28 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:28 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:28 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:28 volumio volumio[1001]: info: Jan 17 19:11:28 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:28 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:28 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:28 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421289&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:28 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:28 volumio volumio[1001]: info: Jan 17 19:11:28 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:28 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:28 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:28 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421289&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:28 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:28 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:28 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:28 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:28 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421289&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:28 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:28 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:28 volumio volumio[1001]: info: getStreamUrl took 533 milliseconds Jan 17 19:11:28 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:28 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:28 volumio volumio[1001]: info: Jan 17 19:11:28 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:28 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:28 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:29 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:29 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:29 volumio volumio[1001]: info: sendMpdCommand status took 15 milliseconds Jan 17 19:11:29 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:29 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:29 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:29 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:29 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:29 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:29 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:29 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:29 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:29 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:29 volumio volumio[1001]: info: CorePlayQueue::getTrack 3 Jan 17 19:11:29 volumio volumio[1001]: 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":"298421289&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421289&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:29 volumio volumio[1001]: verbose: CURRENT POSITION 3 Jan 17 19:11:29 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:29 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:29 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:29 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:29 volumio volumio[1001]: info: No code Jan 17 19:11:29 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:29 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:29 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:29 volumio volumio[1001]: info: ------------------------------ 155ms Jan 17 19:11:29 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:29 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:30 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::play index 4 Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:30 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:30 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:30 volumio volumio[1001]: info: [1737137490015] ControllerTidal::clearAddPlayTrack Jan 17 19:11:30 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:30 volumio volumio[1001]: info: Jan 17 19:11:30 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:30 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421291&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:30 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:30 volumio volumio[1001]: info: Jan 17 19:11:30 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:30 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:30 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421291&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:30 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:30 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421291&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:30 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:30 volumio volumio[1001]: info: getStreamUrl took 309 milliseconds Jan 17 19:11:30 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:30 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:30 volumio volumio[1001]: info: Jan 17 19:11:30 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:30 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:30 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand status took 12 milliseconds Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:30 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:30 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:30 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:30 volumio volumio[1001]: info: CorePlayQueue::getTrack 4 Jan 17 19:11:30 volumio volumio[1001]: 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":"298421291&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421291&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:30 volumio volumio[1001]: verbose: CURRENT POSITION 4 Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:30 volumio volumio[1001]: info: No code Jan 17 19:11:30 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:30 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:30 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:30 volumio volumio[1001]: info: ------------------------------ 136ms Jan 17 19:11:30 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:30 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:31 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::play index 5 Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:31 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:31 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:31 volumio volumio[1001]: info: [1737137491368] ControllerTidal::clearAddPlayTrack Jan 17 19:11:31 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:31 volumio volumio[1001]: info: Jan 17 19:11:31 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:31 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421292&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:31 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:31 volumio volumio[1001]: info: Jan 17 19:11:31 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:31 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:31 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421292&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:31 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:31 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421292&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:31 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:31 volumio sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:11:31 volumio sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:11:31 volumio sudo[7328]: pam_unix(sudo:session): session closed for user root Jan 17 19:11:31 volumio sudo[7331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:11:31 volumio sudo[7331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:11:31 volumio sudo[7331]: pam_unix(sudo:session): session closed for user root Jan 17 19:11:31 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.57 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 17 19:11:31 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:31 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::getQueue Jan 17 19:11:31 volumio volumio[1001]: info: CorePlayQueue::getQueue Jan 17 19:11:31 volumio volumio[1001]: info: Listing playlists Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 19:11:31 volumio volumio[1001]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 19:11:31 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:31 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 19:11:31 volumio volumio[1001]: info: getStreamUrl took 544 milliseconds Jan 17 19:11:31 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:31 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:31 volumio volumio[1001]: info: Jan 17 19:11:31 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:31 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:31 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand status took 12 milliseconds Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand clearerror took 5 milliseconds Jan 17 19:11:31 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:31 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:31 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:31 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:31 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:31 volumio volumio[1001]: info: CorePlayQueue::getTrack 5 Jan 17 19:11:31 volumio volumio[1001]: 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":"298421292&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421292&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:31 volumio volumio[1001]: verbose: CURRENT POSITION 5 Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:31 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:31 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:31 volumio volumio[1001]: info: No code Jan 17 19:11:31 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:31 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:32 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:32 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:32 volumio volumio[1001]: info: ------------------------------ 143ms Jan 17 19:11:32 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:32 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:32 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:32 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::play index 6 Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:32 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:32 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:32 volumio volumio[1001]: info: [1737137492955] ControllerTidal::clearAddPlayTrack Jan 17 19:11:32 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:32 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:32 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:32 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:32 volumio volumio[1001]: info: Jan 17 19:11:32 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:32 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:32 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Jan 17 19:11:32 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421293&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:32 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:32 volumio volumio[1001]: info: Jan 17 19:11:32 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:32 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:32 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:32 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421293&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:32 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:32 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:32 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:32 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:32 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421293&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:32 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:32 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:33 volumio volumio[1001]: info: getStreamUrl took 521 milliseconds Jan 17 19:11:33 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:33 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:33 volumio volumio[1001]: info: Jan 17 19:11:33 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:33 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:33 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:33 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:33 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:33 volumio volumio[1001]: info: sendMpdCommand status took 11 milliseconds Jan 17 19:11:33 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:33 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:33 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:33 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 17 19:11:33 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:33 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:33 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:33 volumio volumio[1001]: info: CorePlayQueue::getTrack 6 Jan 17 19:11:33 volumio volumio[1001]: 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":"298421293&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421293&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:33 volumio volumio[1001]: verbose: CURRENT POSITION 6 Jan 17 19:11:33 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:33 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:33 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:33 volumio volumio[1001]: info: No code Jan 17 19:11:33 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:33 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:33 volumio volumio[1001]: info: ------------------------------ 117ms Jan 17 19:11:33 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:33 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 17 19:11:33 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:33 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:33 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:34 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:34 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::play index 7 Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:34 volumio volumio[1001]: info: CorePlayQueue::getTrack 7 Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:34 volumio volumio[1001]: info: CorePlayQueue::getTrack 7 Jan 17 19:11:34 volumio volumio[1001]: info: [1737137494518] ControllerTidal::clearAddPlayTrack Jan 17 19:11:34 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:34 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:34 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:34 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:34 volumio volumio[1001]: info: Jan 17 19:11:34 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:34 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:34 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:34 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421295&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:34 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:34 volumio volumio[1001]: info: Jan 17 19:11:34 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:34 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:34 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:34 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421295&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:34 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:34 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:34 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:34 volumio volumio[1001]: info: sendMpdCommand play took 3 milliseconds Jan 17 19:11:34 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421295&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:34 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:34 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:34 volumio sudo[7348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 17 19:11:34 volumio sudo[7348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:11:34 volumio sudo[7348]: pam_unix(sudo:session): session closed for user root Jan 17 19:11:34 volumio sudo[7351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 17 19:11:34 volumio sudo[7351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:11:34 volumio sudo[7351]: pam_unix(sudo:session): session closed for user root Jan 17 19:11:35 volumio volumio[1001]: verbose: New Socket.io Connection to 192.168.1.200 from 192.168.1.57 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 17 19:11:35 volumio volumio[1001]: info: getStreamUrl took 514 milliseconds Jan 17 19:11:35 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:35 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:35 volumio volumio[1001]: info: Jan 17 19:11:35 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:35 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:35 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:35 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:35 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:35 volumio volumio[1001]: info: sendMpdCommand status took 9 milliseconds Jan 17 19:11:35 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:35 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:35 volumio volumio[1001]: info: sendMpdCommand clearerror took 3 milliseconds Jan 17 19:11:35 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:35 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:35 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:35 volumio volumio[1001]: info: CorePlayQueue::getTrack 7 Jan 17 19:11:35 volumio volumio[1001]: 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":"298421295&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421295&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:35 volumio volumio[1001]: verbose: CURRENT POSITION 7 Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:35 volumio volumio[1001]: info: No code Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:35 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:35 volumio volumio[1001]: info: ------------------------------ 116ms Jan 17 19:11:35 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:35 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:35 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:35 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetQueue Jan 17 19:11:35 volumio volumio[1001]: info: CoreStateMachine::getQueue Jan 17 19:11:35 volumio volumio[1001]: info: CorePlayQueue::getQueue Jan 17 19:11:35 volumio volumio[1001]: info: Listing playlists Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 17 19:11:35 volumio volumio[1001]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 17 19:11:35 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:35 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: Received Get System Info Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 17 19:11:35 volumio volumio[1001]: info: Discovery: Getting this device information Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Jan 17 19:11:35 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 17 19:11:36 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::play index 8 Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:36 volumio volumio[1001]: info: CorePlayQueue::getTrack 8 Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:36 volumio volumio[1001]: info: CorePlayQueue::getTrack 8 Jan 17 19:11:36 volumio volumio[1001]: info: [1737137496076] ControllerTidal::clearAddPlayTrack Jan 17 19:11:36 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:36 volumio volumio[1001]: info: Jan 17 19:11:36 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:36 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421296&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:36 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:36 volumio volumio[1001]: info: Jan 17 19:11:36 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:36 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:36 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421296&soundQuality=HIGHEST_POSSIBLE" took 3 milliseconds Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:36 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:36 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421296&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:36 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:36 volumio volumio[1001]: info: getStreamUrl took 301 milliseconds Jan 17 19:11:36 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:36 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:36 volumio volumio[1001]: info: Jan 17 19:11:36 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:36 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:36 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:36 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:36 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:36 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:36 volumio volumio[1001]: info: CorePlayQueue::getTrack 8 Jan 17 19:11:36 volumio volumio[1001]: 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":"298421296&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421296&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:36 volumio volumio[1001]: verbose: CURRENT POSITION 8 Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:36 volumio volumio[1001]: info: No code Jan 17 19:11:36 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:36 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:36 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:36 volumio volumio[1001]: info: ------------------------------ 118ms Jan 17 19:11:36 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:36 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:37 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::play index 9 Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 9 Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 9 Jan 17 19:11:37 volumio volumio[1001]: info: [1737137497421] ControllerTidal::clearAddPlayTrack Jan 17 19:11:37 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:37 volumio volumio[1001]: info: Jan 17 19:11:37 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:37 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421297&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:37 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:37 volumio volumio[1001]: info: Jan 17 19:11:37 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:37 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:37 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421297&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:37 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand play took 1 milliseconds Jan 17 19:11:37 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421297&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:37 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:37 volumio volumio[1001]: info: getStreamUrl took 478 milliseconds Jan 17 19:11:37 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:37 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:37 volumio volumio[1001]: info: Jan 17 19:11:37 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:37 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:37 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand status took 9 milliseconds Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand clearerror took 4 milliseconds Jan 17 19:11:37 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:37 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:37 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:37 volumio volumio[1001]: info: CorePlayQueue::getTrack 9 Jan 17 19:11:37 volumio volumio[1001]: 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":"298421297&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421297&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:37 volumio volumio[1001]: verbose: CURRENT POSITION 9 Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:37 volumio volumio[1001]: info: No code Jan 17 19:11:37 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:37 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:37 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:38 volumio volumio[1001]: info: ------------------------------ 114ms Jan 17 19:11:38 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:38 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:38 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:38 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::play index 10 Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:38 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:38 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Jan 17 19:11:38 volumio volumio[1001]: info: [1737137498946] ControllerTidal::clearAddPlayTrack Jan 17 19:11:38 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:38 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:38 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:38 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:38 volumio volumio[1001]: info: Jan 17 19:11:38 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:38 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:38 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:38 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421300&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:38 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:38 volumio volumio[1001]: info: Jan 17 19:11:38 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:38 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:38 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:38 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421300&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:38 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:38 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:38 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:38 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:38 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421300&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:38 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:38 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:39 volumio volumio[1001]: info: getStreamUrl took 571 milliseconds Jan 17 19:11:39 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:39 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:39 volumio volumio[1001]: info: Jan 17 19:11:39 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:39 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:39 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:39 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:39 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:39 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Jan 17 19:11:39 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:39 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:39 volumio volumio[1001]: info: sendMpdCommand clearerror took 3 milliseconds Jan 17 19:11:39 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 17 19:11:39 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:39 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:39 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:39 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:39 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:39 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:39 volumio volumio[1001]: info: CorePlayQueue::getTrack 10 Jan 17 19:11:39 volumio volumio[1001]: 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":"298421300&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421300&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:39 volumio volumio[1001]: verbose: CURRENT POSITION 10 Jan 17 19:11:39 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:39 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:39 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:39 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:39 volumio volumio[1001]: info: No code Jan 17 19:11:39 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:39 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:39 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:39 volumio volumio[1001]: info: ------------------------------ 111ms Jan 17 19:11:39 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:39 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:40 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::play index 11 Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:40 volumio volumio[1001]: info: CorePlayQueue::getTrack 11 Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:40 volumio volumio[1001]: info: CorePlayQueue::getTrack 11 Jan 17 19:11:40 volumio volumio[1001]: info: [1737137500559] ControllerTidal::clearAddPlayTrack Jan 17 19:11:40 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:40 volumio volumio[1001]: info: Jan 17 19:11:40 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:40 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421302&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:40 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:40 volumio volumio[1001]: info: Jan 17 19:11:40 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:40 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:40 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421302&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:40 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:40 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421302&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:40 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:40 volumio volumio[1001]: info: getStreamUrl took 215 milliseconds Jan 17 19:11:40 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:40 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:40 volumio volumio[1001]: info: Jan 17 19:11:40 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:40 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:40 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand status took 10 milliseconds Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand clearerror took 6 milliseconds Jan 17 19:11:40 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 17 19:11:40 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:40 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:40 volumio volumio[1001]: info: CorePlayQueue::getTrack 11 Jan 17 19:11:40 volumio volumio[1001]: 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":"298421302&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421302&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:40 volumio volumio[1001]: verbose: CURRENT POSITION 11 Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:40 volumio volumio[1001]: info: No code Jan 17 19:11:40 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:40 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:40 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:40 volumio volumio[1001]: info: ------------------------------ 111ms Jan 17 19:11:40 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:40 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 19:11:41 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::play index 12 Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:41 volumio volumio[1001]: info: CorePlayQueue::getTrack 12 Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:41 volumio volumio[1001]: info: CorePlayQueue::getTrack 12 Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 17 19:11:41 volumio volumio[1001]: info: [1737137501813] ControllerTidal::clearAddPlayTrack Jan 17 19:11:41 volumio volumio[1001]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Jan 17 19:11:41 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:41 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Jan 17 19:11:41 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:41 volumio volumio[1001]: info: Jan 17 19:11:41 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:41 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:41 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:41 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421305&soundQuality=HIGHEST_POSSIBLE" Jan 17 19:11:41 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:41 volumio volumio[1001]: info: Jan 17 19:11:41 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:41 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:41 volumio volumio[1001]: info: ------------------------------ 3ms Jan 17 19:11:41 volumio volumio[1001]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/298421305&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Jan 17 19:11:41 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:41 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:41 volumio volumio[1001]: info: ------------------------------ 2ms Jan 17 19:11:41 volumio volumio[1001]: info: sendMpdCommand play took 2 milliseconds Jan 17 19:11:41 volumio volumio[1001]: STREAMING PROXY: Handling url /?data=tidal://song/298421305&soundQuality=HIGHEST_POSSIBLE Jan 17 19:11:41 volumio volumio[1001]: info: Executing endpoint getStreamUrltidal Jan 17 19:11:41 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jan 17 19:11:42 volumio volumio[1001]: info: getStreamUrl took 511 milliseconds Jan 17 19:11:42 volumio volumio[1001]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL Jan 17 19:11:42 volumio volumio[1001]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL"} Jan 17 19:11:42 volumio volumio[1001]: info: Jan 17 19:11:42 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:42 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:42 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:42 volumio volumio[1001]: error: MPD Playback Error: Failed to decode stream, moving to next track Jan 17 19:11:42 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 17 19:11:42 volumio volumio[1001]: info: sendMpdCommand status took 9 milliseconds Jan 17 19:11:42 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:42 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:42 volumio volumio[1001]: info: sendMpdCommand clearerror took 6 milliseconds Jan 17 19:11:42 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 17 19:11:42 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:42 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:42 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:42 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:42 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:42 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:42 volumio volumio[1001]: info: CorePlayQueue::getTrack 12 Jan 17 19:11:42 volumio volumio[1001]: 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":"298421305&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/298421305&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Jan 17 19:11:42 volumio volumio[1001]: verbose: CURRENT POSITION 12 Jan 17 19:11:42 volumio volumio[1001]: info: CoreStateMachine::syncState stateService stop Jan 17 19:11:42 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:42 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:42 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:42 volumio volumio[1001]: info: No code Jan 17 19:11:42 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:42 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:42 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:42 volumio volumio[1001]: info: ------------------------------ 112ms Jan 17 19:11:42 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:42 volumio volumio[1001]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.xh1houinxIZJDpa63G4qH7o5UgB3.94e9c1a531ee0411f6ab6e626d84b728.state.status' Jan 17 19:11:43 volumio volumio[1001]: info: Moving to next track after playback error Jan 17 19:11:43 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::play index 13 Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:43 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:43 volumio volumio[1001]: info: CorePlayQueue::getTrack 13 Jan 17 19:11:43 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: rad357pl , handleBrowseUri Jan 17 19:11:43 volumio volumio[1001]: info: [1737137503675] [rad357pl] handleBrowseUri curUri: rad357pl Jan 17 19:11:43 volumio volumio[1001]: info: [1737137503676] [rad357pl] getRadioContent url: rad357pl Jan 17 19:11:43 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:44 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::ClearQueue Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::addQueueItems Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::addQueueItems Jan 17 19:11:44 volumio volumio[1001]: info: Preload queue cleared Jan 17 19:11:44 volumio volumio[1001]: info: Adding Item to queue: webrns/0 Jan 17 19:11:44 volumio volumio[1001]: info: Exploding uri webrns/0 in service rad357pl Jan 17 19:11:44 volumio volumio[1001]: info: [1737137504571] [rad357pl] explodeUri: webrns/0 Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::saveQueue Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::play index 0 Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::stop Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::play index undefined Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Jan 17 19:11:44 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:11:44 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 17 19:11:44 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Jan 17 19:11:44 volumio volumio[1001]: info: sendMpdCommand stop took 8 milliseconds Jan 17 19:11:44 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Jan 17 19:11:44 volumio volumio[1001]: info: Jan 17 19:11:44 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:44 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:44 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Jan 17 19:11:44 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" Jan 17 19:11:44 volumio volumio[1001]: error: updateQueue error: null Jan 17 19:11:44 volumio volumio[1001]: info: Jan 17 19:11:44 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:44 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:44 volumio volumio[1001]: info: ------------------------------ 4ms Jan 17 19:11:44 volumio volumio[1001]: info: sendMpdCommand add "http://stream.rcs.revma.com/ye5kghkgcm0uv" took 3 milliseconds Jan 17 19:11:44 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Jan 17 19:11:44 volumio volumio[1001]: info: ------------------------------ 16ms Jan 17 19:11:44 volumio volumio[1001]: info: sendMpdCommand play took 4 milliseconds Jan 17 19:11:44 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 17 19:11:45 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 17 19:11:47 volumio volumio[1001]: info: Jan 17 19:11:47 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:47 volumio volumio[1001]: info: Jan 17 19:11:47 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand status took 5 milliseconds Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:47 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand status took 4 milliseconds Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:47 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:47 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ye5kghkgcm0uv","artist":null,"album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 17 19:11:47 volumio volumio[1001]: verbose: CURRENT POSITION 0 Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Jan 17 19:11:47 volumio volumio[1001]: info: ------------------------------ 21ms Jan 17 19:11:47 volumio volumio[1001]: info: Jan 17 19:11:47 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Jan 17 19:11:47 volumio volumio[1001]: info: Ignoring MPD Status Update Jan 17 19:11:47 volumio volumio[1001]: info: Jan 17 19:11:47 volumio volumio[1001]: ---------------------------- MPD announces state update: player Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::getState Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:47 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:47 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Novika & Sambor - Fragile","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 17 19:11:47 volumio volumio[1001]: verbose: CURRENT POSITION 0 Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Jan 17 19:11:47 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:47 volumio volumio[1001]: info: ------------------------------ 125ms Jan 17 19:11:47 volumio volumio[1001]: info: ------------------------------ 113ms Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand status took 111 milliseconds Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseState Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 17 19:11:47 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 17 19:11:47 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Jan 17 19:11:47 volumio volumio[1001]: info: ControllerMpd::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Jan 17 19:11:47 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Jan 17 19:11:47 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"130 Kbps","isStreaming":false,"title":"Novika & Sambor - Fragile","artist":"Radio 357","album":null,"uri":"http://stream.rcs.revma.com/ye5kghkgcm0uv","trackType":""} Jan 17 19:11:47 volumio volumio[1001]: verbose: CURRENT POSITION 0 Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus play Jan 17 19:11:47 volumio volumio[1001]: info: Received an update from plugin. extracting info from payload Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:47 volumio volumio[1001]: info: CoreStateMachine::pushState Jan 17 19:11:47 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Jan 17 19:11:47 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Jan 17 19:11:47 volumio volumio[1001]: info: ------------------------------ 272ms Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: Retrieving Cloud Streaming UI Jan 17 19:11:59 volumio volumio[1001]: info: Getting Tidal Cloud Configuration Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: Getting Qobuz Cloud Configuration Jan 17 19:11:59 volumio volumio[1001]: info: Asking plugin for UI Config Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: Getting Spotify Cloud Configuration Jan 17 19:11:59 volumio volumio[1001]: info: Asking plugin for UI Config Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: Saving Spotify Acccount Jan 17 19:11:59 volumio volumio[1001]: info: Got it Jan 17 19:11:59 volumio volumio[1001]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 17 19:11:59 volumio volumio[1001]: info: Got Tidal Cloud Configuration Jan 17 19:11:59 volumio volumio[1001]: info: Got it Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:11:59 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 19:12:00 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 17 19:12:03 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 19:12:07 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 19:12:08 volumio volumio[1001]: info: CALLMETHOD: music_service streaming_services tidalLogout Jan 17 19:12:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: streaming_services , tidalLogout Jan 17 19:12:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: tidal , logout Jan 17 19:12:08 volumio volumio[1001]: info: Executing Logout From TIDAL Jan 17 19:12:08 volumio volumio[1001]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jan 17 19:12:08 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 17 19:12:08 volumio volumio[1001]: Cannot find translation for source RADIO 357 Jan 17 19:12:08 volumio volumio[1001]: Cannot find translation for source Radio Paradise Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getUIConfig Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:12:09 volumio volumio[1001]: info: Retrieving Cloud Streaming UI Jan 17 19:12:09 volumio volumio[1001]: info: Getting Tidal Cloud Configuration Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:12:09 volumio volumio[1001]: info: Getting Qobuz Cloud Configuration Jan 17 19:12:09 volumio volumio[1001]: info: Asking plugin for UI Config Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:12:09 volumio volumio[1001]: info: Getting Spotify Cloud Configuration Jan 17 19:12:09 volumio volumio[1001]: info: Asking plugin for UI Config Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:12:09 volumio volumio[1001]: info: Saving Spotify Acccount Jan 17 19:12:09 volumio volumio[1001]: info: Got it Jan 17 19:12:09 volumio volumio[1001]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 17 19:12:09 volumio volumio[1001]: info: Got Tidal Cloud Configuration Jan 17 19:12:09 volumio volumio[1001]: info: Got it Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:12:09 volumio volumio[1001]: info: CoreCommandRouter::volumioGetBrowseSources Jan 17 19:12:10 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 17 19:12:10 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 17 19:12:12 volumio volumio[1001]: info: Disabling MyMusic plugin upnp Jan 17 19:12:12 volumio sudo[7457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 17 19:12:12 volumio sudo[7457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 17 19:12:12 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Jan 17 19:12:12 volumio volumio[1001]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 17 19:12:12 volumio volumio[1001]: error: Upnp client error: Error: This socket has been ended by the other party Jan 17 19:12:13 volumio volumio[1001]: info: Enabling MyMusic plugin upnp Jan 17 19:12:13 volumio volumio[1001]: info: Enabling plugin upnp Jan 17 19:12:13 volumio volumio[1001]: info: Loading plugin "upnp"... Jan 17 19:12:13 volumio volumio[1001]: info: [1737137533298] Starting Upmpd Daemon Jan 17 19:12:13 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 17 19:12:13 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:12:13 volumio volumio[1001]: Error: listen EADDRINUSE: address already in use :::6599 Jan 17 19:12:13 volumio volumio[1001]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jan 17 19:12:13 volumio volumio[1001]: at listenInCluster (net.js:1366:12) Jan 17 19:12:13 volumio volumio[1001]: at Server.listen (net.js:1452:7) Jan 17 19:12:13 volumio volumio[1001]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 17 19:12:13 volumio volumio[1001]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 17 19:12:13 volumio volumio[1001]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jan 17 19:12:13 volumio volumio[1001]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 17 19:12:13 volumio volumio[1001]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jan 17 19:12:13 volumio volumio[1001]: code: 'EADDRINUSE', Jan 17 19:12:13 volumio volumio[1001]: errno: -98, Jan 17 19:12:13 volumio volumio[1001]: syscall: 'listen', Jan 17 19:12:13 volumio volumio[1001]: address: '::', Jan 17 19:12:13 volumio volumio[1001]: port: 6599 Jan 17 19:12:13 volumio volumio[1001]: } Jan 17 19:12:13 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 17 19:12:13 volumio sudo[7468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-17 19:11 Jan 17 19:12:13 volumio sudo[7468]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"