-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sat 2024-11-23 23:46:01 +04. -- Nov 23 23:45:00 balonias-volumio volumio[1074]: info: getStreamUrl took 704 milliseconds Nov 23 23:45:00 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:00 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:00 balonias-volumio volumio[1074]: info: Nov 23 23:45:00 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:00 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:00 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:00 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:00 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 3 milliseconds Nov 23 23:45:00 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:00 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 23 23:45:00 balonias-volumio volumio[1074]: 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":"1288048&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288048&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:00 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:00 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:00 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:00 balonias-volumio volumio[1074]: info: ------------------------------ 21ms Nov 23 23:45:00 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:00 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 8 Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:01 balonias-volumio volumio[1074]: info: [1732391101176] ControllerTidal::clearAddPlayTrack Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Nov 23 23:45:01 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:01 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Nov 23 23:45:01 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:01 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:01 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:01 balonias-volumio volumio[1074]: info: getStreamUrl took 618 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:01 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:01 balonias-volumio volumio[1074]: info: Nov 23 23:45:01 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:01 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:01 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:01 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:01 balonias-volumio volumio[1074]: 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":"1288049&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:01 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 8 Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:01 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:01 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:01 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:01 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:01 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 9 Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:02 balonias-volumio volumio[1074]: info: [1732391102806] ControllerTidal::clearAddPlayTrack Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:02 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:02 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:02 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Nov 23 23:45:02 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:02 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:02 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:02 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Nov 23 23:45:02 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:02 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:02 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:02 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:02 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:02 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:02 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:02 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:02 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:03 balonias-volumio volumio[1074]: info: getStreamUrl took 607 milliseconds Nov 23 23:45:03 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:03 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:03 balonias-volumio volumio[1074]: info: Nov 23 23:45:03 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:03 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:03 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:03 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:03 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:03 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:03 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:03 balonias-volumio volumio[1074]: 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":"1288050&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:03 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 9 Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:03 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:03 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:03 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:03 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:03 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 10 Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:04 balonias-volumio volumio[1074]: info: [1732391104426] ControllerTidal::clearAddPlayTrack Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:04 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:04 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:04 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Nov 23 23:45:04 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:04 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:04 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:04 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Nov 23 23:45:04 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:04 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:04 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:04 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:04 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:04 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:04 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:04 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:04 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:05 balonias-volumio volumio[1074]: info: getStreamUrl took 610 milliseconds Nov 23 23:45:05 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:05 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:05 balonias-volumio volumio[1074]: info: Nov 23 23:45:05 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:05 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:05 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:05 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:05 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:05 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:05 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:05 balonias-volumio volumio[1074]: 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":"1288051&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:05 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 10 Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:05 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:05 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:05 balonias-volumio volumio[1074]: info: ------------------------------ 20ms Nov 23 23:45:05 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:05 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 11 Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:06 balonias-volumio volumio[1074]: info: [1732391106048] ControllerTidal::clearAddPlayTrack Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Nov 23 23:45:06 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:06 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Nov 23 23:45:06 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:06 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:06 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:06 balonias-volumio volumio[1074]: info: getStreamUrl took 715 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:06 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:06 balonias-volumio volumio[1074]: info: Nov 23 23:45:06 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:06 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:06 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:06 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:06 balonias-volumio volumio[1074]: 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":"1288052&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:06 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 11 Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:06 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:06 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:06 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:06 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:06 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 12 Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:07 balonias-volumio volumio[1074]: info: [1732391107777] ControllerTidal::clearAddPlayTrack Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:07 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:07 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:07 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Nov 23 23:45:07 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:07 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:07 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:07 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Nov 23 23:45:07 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:07 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:07 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:07 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:07 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:07 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:07 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:07 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:07 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:08 balonias-volumio volumio[1074]: info: getStreamUrl took 1162 milliseconds Nov 23 23:45:08 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:08 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:08 balonias-volumio volumio[1074]: info: Nov 23 23:45:08 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:08 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:08 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:08 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:08 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:08 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:08 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:08 balonias-volumio volumio[1074]: 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":"1288053&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:08 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 12 Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:08 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:08 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:08 balonias-volumio volumio[1074]: info: ------------------------------ 16ms Nov 23 23:45:08 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:08 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 13 Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:09 balonias-volumio volumio[1074]: info: [1732391109952] ControllerTidal::clearAddPlayTrack Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:09 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:09 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:09 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Nov 23 23:45:09 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:09 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:09 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:09 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Nov 23 23:45:09 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:09 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:09 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:09 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:09 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:09 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:09 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:09 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:09 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:10 balonias-volumio volumio[1074]: info: getStreamUrl took 736 milliseconds Nov 23 23:45:10 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:10 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:10 balonias-volumio volumio[1074]: info: Nov 23 23:45:10 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:10 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:10 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:10 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:10 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:10 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:10 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:10 balonias-volumio volumio[1074]: 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":"1288054&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:10 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 13 Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:10 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:10 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:10 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:10 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:10 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 14 Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:11 balonias-volumio volumio[1074]: info: [1732391111700] ControllerTidal::clearAddPlayTrack Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:11 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:11 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:11 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Nov 23 23:45:11 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:11 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:11 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:11 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Nov 23 23:45:11 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:11 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:11 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:11 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:11 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:11 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:11 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:11 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:11 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:12 balonias-volumio volumio[1074]: info: getStreamUrl took 679 milliseconds Nov 23 23:45:12 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:12 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:12 balonias-volumio volumio[1074]: info: Nov 23 23:45:12 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:12 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:12 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:12 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:12 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:12 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:12 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:12 balonias-volumio volumio[1074]: 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":"1288055&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:12 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 14 Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:12 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:12 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:12 balonias-volumio volumio[1074]: info: ------------------------------ 20ms Nov 23 23:45:12 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:12 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 15 Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 23:45:13 balonias-volumio volumio[1074]: info: [1732391113392] ControllerTidal::clearAddPlayTrack Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:13 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:13 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 1 milliseconds Nov 23 23:45:13 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Nov 23 23:45:13 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:13 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:13 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:13 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Nov 23 23:45:13 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:13 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:13 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:13 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:13 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:13 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:13 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:13 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:13 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:14 balonias-volumio volumio[1074]: info: getStreamUrl took 803 milliseconds Nov 23 23:45:14 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:14 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:14 balonias-volumio volumio[1074]: info: Nov 23 23:45:14 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:14 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:14 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:14 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:14 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:14 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:14 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:14 balonias-volumio volumio[1074]: 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":"1288056&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:14 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 15 Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:14 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:14 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:14 balonias-volumio volumio[1074]: info: ------------------------------ 18ms Nov 23 23:45:14 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:14 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:15 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:15 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 16 Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:15 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 16 Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:22 balonias-volumio volumio[1074]: info: [1732391122059] ControllerTidal::clearAddPlayTrack Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Nov 23 23:45:22 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1708554&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:22 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Nov 23 23:45:22 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:22 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1708554&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:22 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1708554&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:22 balonias-volumio volumio[1074]: info: getStreamUrl took 633 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:22 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:22 balonias-volumio volumio[1074]: info: Nov 23 23:45:22 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:22 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:22 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand status took 4 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:22 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:22 balonias-volumio volumio[1074]: 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":"1708554&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1708554&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:22 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 0 Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:22 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:22 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:22 balonias-volumio volumio[1074]: info: ------------------------------ 20ms Nov 23 23:45:22 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:22 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 1 Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 1 Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 1 Nov 23 23:45:23 balonias-volumio volumio[1074]: info: [1732391123705] ControllerTidal::clearAddPlayTrack Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:23 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:23 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:23 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Nov 23 23:45:23 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:23 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:23 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1708555&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:23 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Nov 23 23:45:23 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:23 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:23 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1708555&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:23 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:23 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:23 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:23 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1708555&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:23 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:23 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:24 balonias-volumio volumio[1074]: info: getStreamUrl took 608 milliseconds Nov 23 23:45:24 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:24 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:24 balonias-volumio volumio[1074]: info: Nov 23 23:45:24 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:24 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:24 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:24 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:24 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:24 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:24 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 1 Nov 23 23:45:24 balonias-volumio volumio[1074]: 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":"1708555&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1708555&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:24 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 1 Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:24 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:24 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:24 balonias-volumio volumio[1074]: info: ------------------------------ 19ms Nov 23 23:45:24 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:24 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 2 Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 2 Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 2 Nov 23 23:45:25 balonias-volumio volumio[1074]: info: [1732391125324] ControllerTidal::clearAddPlayTrack Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Nov 23 23:45:25 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288043&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:25 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Nov 23 23:45:25 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:25 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288043&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:25 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288043&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:25 balonias-volumio volumio[1074]: info: getStreamUrl took 598 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:25 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:25 balonias-volumio volumio[1074]: info: Nov 23 23:45:25 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:25 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:25 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:25 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 2 Nov 23 23:45:25 balonias-volumio volumio[1074]: 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":"1288043&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288043&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:25 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 2 Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:25 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:25 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:25 balonias-volumio volumio[1074]: info: ------------------------------ 16ms Nov 23 23:45:25 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:25 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 3 Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 3 Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 3 Nov 23 23:45:26 balonias-volumio volumio[1074]: info: [1732391126934] ControllerTidal::clearAddPlayTrack Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:26 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:26 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:26 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Nov 23 23:45:26 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:26 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:26 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288044&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:26 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Nov 23 23:45:26 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:26 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:26 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288044&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:26 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:26 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:26 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:26 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288044&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:26 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:26 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:27 balonias-volumio ntpd[965]: Soliciting pool server 2400:8902:e001:347:0:a789:b456:c123 Nov 23 23:45:27 balonias-volumio volumio[1074]: info: getStreamUrl took 688 milliseconds Nov 23 23:45:27 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:27 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:27 balonias-volumio volumio[1074]: info: Nov 23 23:45:27 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:27 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:27 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:27 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:27 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:27 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:27 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 3 Nov 23 23:45:27 balonias-volumio volumio[1074]: 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":"1288044&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288044&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:27 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 3 Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:27 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:27 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:27 balonias-volumio volumio[1074]: info: ------------------------------ 18ms Nov 23 23:45:27 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:27 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 4 Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 4 Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 4 Nov 23 23:45:28 balonias-volumio volumio[1074]: info: [1732391128638] ControllerTidal::clearAddPlayTrack Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:28 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:28 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:28 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Nov 23 23:45:28 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:28 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:28 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288045&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:28 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Nov 23 23:45:28 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:28 balonias-volumio volumio[1074]: info: ------------------------------ 2ms Nov 23 23:45:28 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288045&soundQuality=HIGHEST_POSSIBLE" took 2 milliseconds Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:28 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:28 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:28 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:28 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288045&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:28 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:28 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:29 balonias-volumio volumio[1074]: info: getStreamUrl took 711 milliseconds Nov 23 23:45:29 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:29 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:29 balonias-volumio volumio[1074]: info: Nov 23 23:45:29 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:29 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:29 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:29 balonias-volumio volumio[1074]: info: sendMpdCommand status took 1 milliseconds Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:29 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:29 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:29 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 4 Nov 23 23:45:29 balonias-volumio volumio[1074]: 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":"1288045&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288045&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:29 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 4 Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:29 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:29 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:29 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:29 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:29 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 5 Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 5 Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 5 Nov 23 23:45:30 balonias-volumio volumio[1074]: info: [1732391130362] ControllerTidal::clearAddPlayTrack Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288046&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Nov 23 23:45:30 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Nov 23 23:45:30 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288046&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:30 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:30 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288046&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:30 balonias-volumio volumio[1074]: info: getStreamUrl took 617 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:30 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:30 balonias-volumio volumio[1074]: info: Nov 23 23:45:30 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:30 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:30 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:30 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 5 Nov 23 23:45:30 balonias-volumio volumio[1074]: 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":"1288046&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288046&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:30 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 5 Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:30 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:30 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:31 balonias-volumio volumio[1074]: info: ------------------------------ 14ms Nov 23 23:45:31 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:31 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:31 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121:3000 from 192.168.8.123 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 6 Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 6 Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 6 Nov 23 23:45:31 balonias-volumio volumio[1074]: info: [1732391131991] ControllerTidal::clearAddPlayTrack Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:31 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:31 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:31 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Nov 23 23:45:31 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:31 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:31 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288047&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:31 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Nov 23 23:45:31 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:31 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:31 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288047&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:31 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:31 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:31 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:31 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288047&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:31 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:31 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:32 balonias-volumio volumio[1074]: info: getStreamUrl took 796 milliseconds Nov 23 23:45:32 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:32 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:32 balonias-volumio volumio[1074]: info: Nov 23 23:45:32 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:32 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:32 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:32 balonias-volumio volumio[1074]: info: sendMpdCommand status took 4 milliseconds Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:32 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:32 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:32 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 6 Nov 23 23:45:32 balonias-volumio volumio[1074]: 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":"1288047&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288047&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:32 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 6 Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:32 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:32 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:32 balonias-volumio volumio[1074]: info: ------------------------------ 19ms Nov 23 23:45:32 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:32 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:33 balonias-volumio sudo[7331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 23:45:33 balonias-volumio sudo[7331]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 23:45:33 balonias-volumio sudo[7331]: pam_unix(sudo:session): session closed for user root Nov 23 23:45:33 balonias-volumio sudo[7334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 23:45:33 balonias-volumio sudo[7334]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 23:45:33 balonias-volumio sudo[7334]: pam_unix(sudo:session): session closed for user root Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 7 Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 23 23:45:33 balonias-volumio volumio[1074]: info: [1732391133801] ControllerTidal::clearAddPlayTrack Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:33 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 2 milliseconds Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Nov 23 23:45:33 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:33 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 2 milliseconds Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288048&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:33 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Nov 23 23:45:33 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:33 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:33 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288048&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:33 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:33 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:33 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288048&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:33 balonias-volumio sudo[7337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 23 23:45:33 balonias-volumio sudo[7337]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 23:45:33 balonias-volumio sudo[7337]: pam_unix(sudo:session): session closed for user root Nov 23 23:45:33 balonias-volumio sudo[7340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 23 23:45:33 balonias-volumio sudo[7340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 23:45:33 balonias-volumio sudo[7340]: pam_unix(sudo:session): session closed for user root Nov 23 23:45:33 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 23 23:45:33 balonias-volumio volumio[1074]: info: Listing playlists Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 23 23:45:33 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 23:45:34 balonias-volumio volumio[1074]: info: getStreamUrl took 703 milliseconds Nov 23 23:45:34 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:34 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:34 balonias-volumio volumio[1074]: info: Nov 23 23:45:34 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:34 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:34 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:34 balonias-volumio volumio[1074]: info: sendMpdCommand status took 5 milliseconds Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:34 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:34 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:34 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 7 Nov 23 23:45:34 balonias-volumio volumio[1074]: 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":"1288048&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288048&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:34 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 7 Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:34 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:34 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:34 balonias-volumio volumio[1074]: info: ------------------------------ 23ms Nov 23 23:45:34 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:34 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 8 Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:35 balonias-volumio volumio[1074]: info: [1732391135521] ControllerTidal::clearAddPlayTrack Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:35 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:35 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:35 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Nov 23 23:45:35 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:35 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:35 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:35 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:35 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:35 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:35 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Nov 23 23:45:35 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:35 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:35 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:35 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:35 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:35 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:36 balonias-volumio volumio[1074]: info: getStreamUrl took 700 milliseconds Nov 23 23:45:36 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:36 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:36 balonias-volumio volumio[1074]: info: Nov 23 23:45:36 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:36 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:36 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:36 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:36 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:36 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:36 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:36 balonias-volumio volumio[1074]: 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":"1288049&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:36 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 8 Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:36 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:36 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:36 balonias-volumio volumio[1074]: info: ------------------------------ 16ms Nov 23 23:45:36 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 8 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: [1732391137044] ControllerTidal::clearAddPlayTrack Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:37 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288049&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 9 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: [1732391137232] ControllerTidal::clearAddPlayTrack Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand status took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:37 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:37 balonias-volumio volumio[1074]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 3ms Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 2ms Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:37 balonias-volumio volumio[1074]: info: getStreamUrl took 599 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:37 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:37 balonias-volumio volumio[1074]: info: getStreamUrl took 595 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:37 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:37 balonias-volumio volumio[1074]: info: Nov 23 23:45:37 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:37 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 9 Nov 23 23:45:37 balonias-volumio volumio[1074]: 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":"1288050&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288050&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:37 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 9 Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:37 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:37 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:37 balonias-volumio volumio[1074]: info: ------------------------------ 18ms Nov 23 23:45:37 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:37 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 10 Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:38 balonias-volumio volumio[1074]: info: [1732391138839] ControllerTidal::clearAddPlayTrack Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:38 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:38 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:38 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Nov 23 23:45:38 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:38 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:38 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:38 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Nov 23 23:45:38 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:38 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:38 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:38 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:38 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:38 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:38 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:38 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:38 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:39 balonias-volumio volumio[1074]: info: getStreamUrl took 615 milliseconds Nov 23 23:45:39 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:39 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:39 balonias-volumio volumio[1074]: info: Nov 23 23:45:39 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:39 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:39 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:39 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:39 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:39 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:39 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 10 Nov 23 23:45:39 balonias-volumio volumio[1074]: 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":"1288051&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288051&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:39 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 10 Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:39 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:39 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:39 balonias-volumio volumio[1074]: info: ------------------------------ 20ms Nov 23 23:45:39 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:39 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 11 Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:40 balonias-volumio volumio[1074]: info: [1732391140468] ControllerTidal::clearAddPlayTrack Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:40 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:40 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:40 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Nov 23 23:45:40 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:40 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:40 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:40 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Nov 23 23:45:40 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:40 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:40 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:40 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:40 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:40 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:40 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:40 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:40 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:41 balonias-volumio volumio[1074]: info: getStreamUrl took 630 milliseconds Nov 23 23:45:41 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:41 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:41 balonias-volumio volumio[1074]: info: Nov 23 23:45:41 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:41 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:41 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:41 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:41 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:41 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:41 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 11 Nov 23 23:45:41 balonias-volumio volumio[1074]: 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":"1288052&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288052&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:41 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 11 Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:41 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:41 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:41 balonias-volumio volumio[1074]: info: ------------------------------ 20ms Nov 23 23:45:41 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:41 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 12 Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:42 balonias-volumio volumio[1074]: info: [1732391142112] ControllerTidal::clearAddPlayTrack Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:42 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:42 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:42 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Nov 23 23:45:42 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:42 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:42 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:42 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Nov 23 23:45:42 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:42 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:42 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:42 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:42 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:42 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:42 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:42 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:42 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:43 balonias-volumio volumio[1074]: info: getStreamUrl took 910 milliseconds Nov 23 23:45:43 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:43 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:43 balonias-volumio volumio[1074]: info: Nov 23 23:45:43 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:43 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:43 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:43 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:43 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 1 milliseconds Nov 23 23:45:43 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:43 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 12 Nov 23 23:45:43 balonias-volumio volumio[1074]: 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":"1288053&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288053&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:43 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 12 Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:43 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:43 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:43 balonias-volumio volumio[1074]: info: ------------------------------ 18ms Nov 23 23:45:43 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:43 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 13 Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:44 balonias-volumio volumio[1074]: info: [1732391144033] ControllerTidal::clearAddPlayTrack Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Nov 23 23:45:44 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:44 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Nov 23 23:45:44 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:44 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:44 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:44 balonias-volumio volumio[1074]: info: getStreamUrl took 612 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:44 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:44 balonias-volumio volumio[1074]: info: Nov 23 23:45:44 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:44 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:44 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:44 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 13 Nov 23 23:45:44 balonias-volumio volumio[1074]: 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":"1288054&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288054&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:44 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 13 Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:44 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:44 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:44 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:44 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:44 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 14 Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:45 balonias-volumio volumio[1074]: info: [1732391145655] ControllerTidal::clearAddPlayTrack Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:45 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:45 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 0 milliseconds Nov 23 23:45:45 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Nov 23 23:45:45 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:45 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 1 milliseconds Nov 23 23:45:45 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:45 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Nov 23 23:45:45 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:45 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:45 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:45 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:45 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:45 balonias-volumio volumio[1074]: info: sendMpdCommand play took 0 milliseconds Nov 23 23:45:45 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:45 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:45 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:46 balonias-volumio volumio[1074]: info: getStreamUrl took 629 milliseconds Nov 23 23:45:46 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:46 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:46 balonias-volumio volumio[1074]: info: Nov 23 23:45:46 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:46 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:46 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:46 balonias-volumio volumio[1074]: info: sendMpdCommand status took 2 milliseconds Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:46 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 2 milliseconds Nov 23 23:45:46 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:46 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 14 Nov 23 23:45:46 balonias-volumio volumio[1074]: 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":"1288055&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288055&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:46 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 14 Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:46 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:46 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:46 balonias-volumio volumio[1074]: info: ------------------------------ 18ms Nov 23 23:45:46 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:46 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 15 Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::startPlaybackTimer Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetVisibleSources Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 23 23:45:47 balonias-volumio volumio[1074]: info: [1732391147294] ControllerTidal::clearAddPlayTrack Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Nov 23 23:45:47 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand stop Nov 23 23:45:47 balonias-volumio volumio[1074]: info: sendMpdCommand stop took 1 milliseconds Nov 23 23:45:47 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clear Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Nov 23 23:45:47 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:47 balonias-volumio volumio[1074]: info: sendMpdCommand clear took 0 milliseconds Nov 23 23:45:47 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE" Nov 23 23:45:47 balonias-volumio volumio[1074]: error: updateQueue error: null Nov 23 23:45:47 balonias-volumio volumio[1074]: info: ------------------------------ 0ms Nov 23 23:45:47 balonias-volumio volumio[1074]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE" took 0 milliseconds Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 23 23:45:47 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand play Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Nov 23 23:45:47 balonias-volumio volumio[1074]: ---------------------------- MPD announces system playlist update Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Ignoring MPD Status Update Nov 23 23:45:47 balonias-volumio volumio[1074]: info: sendMpdCommand play took 1 milliseconds Nov 23 23:45:47 balonias-volumio volumio[1074]: info: ------------------------------ 1ms Nov 23 23:45:47 balonias-volumio volumio[1074]: STREAMING PROXY: Handling url /?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE Nov 23 23:45:47 balonias-volumio volumio[1074]: info: Executing endpoint getStreamUrltidal Nov 23 23:45:47 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 23 23:45:48 balonias-volumio volumio[1074]: info: getStreamUrl took 804 milliseconds Nov 23 23:45:48 balonias-volumio volumio[1074]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {"status":404,"subStatus":2001,"userMessage":"Resource not found"} Nov 23 23:45:48 balonias-volumio volumio[1074]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = NotFound desc = failed to get track playback info: got 404: {\"status\":404,\"subStatus\":2001,\"userMessage\":\"Resource not found\"}"} Nov 23 23:45:48 balonias-volumio volumio[1074]: info: Nov 23 23:45:48 balonias-volumio volumio[1074]: ---------------------------- MPD announces state update: player Nov 23 23:45:48 balonias-volumio volumio[1074]: info: ControllerMpd::getState Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand status Nov 23 23:45:48 balonias-volumio volumio[1074]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 23 23:45:48 balonias-volumio volumio[1074]: info: sendMpdCommand status took 3 milliseconds Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseState Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 23 23:45:48 balonias-volumio volumio[1074]: info: sendMpdCommand clearerror took 0 milliseconds Nov 23 23:45:48 balonias-volumio volumio[1074]: info: sendMpdCommand playlistinfo took 0 milliseconds Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: ControllerMpd::parseTrackInfo Nov 23 23:45:48 balonias-volumio volumio[1074]: info: ControllerMpd::pushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::servicePushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 15 Nov 23 23:45:48 balonias-volumio volumio[1074]: 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":"1288056&soundQuality=HIGHEST_POSSIBLE","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/1288056&soundQuality=HIGHEST_POSSIBLE","trackType":"tidal"} Nov 23 23:45:48 balonias-volumio volumio[1074]: verbose: CURRENT POSITION 15 Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState stateService stop Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreStateMachine::syncState currentStatus stop Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:48 balonias-volumio volumio[1074]: info: No code Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreStateMachine::pushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPushState Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output update for this device Nov 23 23:45:48 balonias-volumio volumio[1074]: info: MRS: Pushing multiroomSync output Nov 23 23:45:48 balonias-volumio volumio[1074]: info: ------------------------------ 17ms Nov 23 23:45:48 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:48 balonias-volumio volumio[1074]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.bio3czC0QwWrgh8ctw7hZeAUDXG2.6f65d13945d6f4a47b12af561ac7c194.state.status' Nov 23 23:45:49 balonias-volumio volumio[1074]: info: Moving to next track after playback error Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioPlay Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::play index 16 Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::stop Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::play index undefined Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 16 Nov 23 23:45:49 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:49 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 23:45:49 balonias-volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 23 23:45:49 balonias-volumio volumio[1074]: info: Listing playlists Nov 23 23:45:52 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 23:45:52 balonias-volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 23 23:45:52 balonias-volumio volumio[1074]: info: Listing playlists Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 23 23:45:52 balonias-volumio volumio[1074]: info: Received Get System Version Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 23 23:45:52 balonias-volumio volumio[1074]: info: Received Get System Info Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:45:52 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:52 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:45:52 balonias-volumio volumio[1074]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.123 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_7_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Nov 23 23:45:53 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:45:53 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:45:53 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 23 23:45:53 balonias-volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 23 23:45:53 balonias-volumio volumio[1074]: info: Listing playlists Nov 23 23:46:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 23:46:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 23:46:00 balonias-volumio volumio[1074]: info: Discovery: Getting this device information Nov 23 23:46:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::volumioGetState Nov 23 23:46:00 balonias-volumio volumio[1074]: info: CorePlayQueue::getTrack 0 Nov 23 23:46:00 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 23:46:01 balonias-volumio volumio[1074]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Nov 23 23:46:01 balonias-volumio volumio[1074]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Nov 23 23:46:01 balonias-volumio volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 23:46:01 balonias-volumio volumio[1074]: TypeError: Cannot read property 'then' of undefined Nov 23 23:46:01 balonias-volumio volumio[1074]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Nov 23 23:46:01 balonias-volumio volumio[1074]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2274:30) Nov 23 23:46:01 balonias-volumio volumio[1074]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1450:26) Nov 23 23:46:01 balonias-volumio volumio[1074]: at Socket.emit (events.js:315:20) Nov 23 23:46:01 balonias-volumio volumio[1074]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 23 23:46:01 balonias-volumio volumio[1074]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 23 23:46:01 balonias-volumio volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 23:46:01 balonias-volumio sudo[7403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 23:45 Nov 23 23:46:01 balonias-volumio sudo[7403]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"