-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2024-11-20 21:33:38 CET. -- Nov 20 21:32:00 volumio-bureau volumio[893]: info: TidalConnect service stoped! Nov 20 21:32:00 volumio-bureau volumio[893]: info: TidalConnect service stoped! Nov 20 21:32:00 volumio-bureau volumio[893]: info: TidalConnect service stoped! Nov 20 21:32:00 volumio-bureau volumio[893]: info: TidalConnect service stoped! Nov 20 21:32:00 volumio-bureau volumio[893]: info: TidalConnect service stoped! Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau sudo[3497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 20 21:32:00 volumio-bureau sudo[3497]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 21:32:00 volumio-bureau sudo[3497]: pam_unix(sudo:session): session closed for user root Nov 20 21:32:00 volumio-bureau sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 20 21:32:00 volumio-bureau sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau volumio[893]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 20 21:32:00 volumio-bureau sudo[3500]: pam_unix(sudo:session): session closed for user root Nov 20 21:32:00 volumio-bureau sudo[3509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 20 21:32:00 volumio-bureau sudo[3509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 21:32:00 volumio-bureau sudo[3509]: pam_unix(sudo:session): session closed for user root Nov 20 21:32:01 volumio-bureau volumio[893]: info: TidalConnect service started! Nov 20 21:32:01 volumio-bureau volumio[893]: info: TidalConnect service started! Nov 20 21:32:03 volumio-bureau volumio[893]: info: TidalConnect service started! Nov 20 21:32:03 volumio-bureau volumio[893]: info: TidalConnect service started! Nov 20 21:32:03 volumio-bureau volumio[893]: info: TidalConnect service started! Nov 20 21:32:11 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:11 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:15 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:15 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:15 volumio-bureau volumio[893]: info: [1732134735271] ControllerTidal::clearAddPlayTrack Nov 20 21:32:15 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:15 volumio-bureau volumio[893]: info: Nov 20 21:32:15 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:15 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645351&soundQuality=HIGH" Nov 20 21:32:15 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:15 volumio-bureau volumio[893]: info: Nov 20 21:32:15 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:15 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:15 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645351&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:15 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645351&soundQuality=HIGH Nov 20 21:32:15 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:15 volumio-bureau volumio[893]: info: getStreamUrl took 398 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:15 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:15 volumio-bureau volumio[893]: info: Nov 20 21:32:15 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:15 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:15 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand status took 4 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 3 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:15 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:15 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:15 volumio-bureau volumio[893]: 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":"393645351&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645351&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:15 volumio-bureau volumio[893]: verbose: CURRENT POSITION 0 Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:15 volumio-bureau volumio[893]: info: No code Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:15 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:15 volumio-bureau volumio[893]: info: ------------------------------ 40ms Nov 20 21:32:15 volumio-bureau volumio[893]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 20 21:32:16 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::play index 1 Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:16 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 1 Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:16 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 1 Nov 20 21:32:16 volumio-bureau volumio[893]: info: [1732134736693] ControllerTidal::clearAddPlayTrack Nov 20 21:32:16 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:16 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:16 volumio-bureau volumio[893]: info: sendMpdCommand stop took 0 milliseconds Nov 20 21:32:16 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:16 volumio-bureau volumio[893]: info: Nov 20 21:32:16 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:16 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:16 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:16 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645352&soundQuality=HIGH" Nov 20 21:32:16 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:16 volumio-bureau volumio[893]: info: Nov 20 21:32:16 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:16 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:16 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:16 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645352&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:16 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:16 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:16 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:16 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645352&soundQuality=HIGH Nov 20 21:32:16 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:16 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:17 volumio-bureau volumio[893]: info: getStreamUrl took 379 milliseconds Nov 20 21:32:17 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:17 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:17 volumio-bureau volumio[893]: info: Nov 20 21:32:17 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:17 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:17 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:17 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:17 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:17 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:17 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:17 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 1 Nov 20 21:32:17 volumio-bureau volumio[893]: 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":"393645352&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645352&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:17 volumio-bureau volumio[893]: verbose: CURRENT POSITION 1 Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:17 volumio-bureau volumio[893]: info: No code Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:17 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:17 volumio-bureau volumio[893]: info: ------------------------------ 46ms Nov 20 21:32:18 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::play index 2 Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:18 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 2 Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:18 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 2 Nov 20 21:32:18 volumio-bureau volumio[893]: info: [1732134738094] ControllerTidal::clearAddPlayTrack Nov 20 21:32:18 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand stop took 0 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:18 volumio-bureau volumio[893]: info: Nov 20 21:32:18 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:18 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645354&soundQuality=HIGH" Nov 20 21:32:18 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:18 volumio-bureau volumio[893]: info: Nov 20 21:32:18 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:18 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:18 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645354&soundQuality=HIGH" took 4 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:18 volumio-bureau volumio[893]: info: ------------------------------ 5ms Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand play took 2 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645354&soundQuality=HIGH Nov 20 21:32:18 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:18 volumio-bureau volumio[893]: info: getStreamUrl took 378 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:18 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:18 volumio-bureau volumio[893]: info: Nov 20 21:32:18 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:18 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:18 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:18 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:18 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 2 Nov 20 21:32:18 volumio-bureau volumio[893]: 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":"393645354&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645354&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:18 volumio-bureau volumio[893]: verbose: CURRENT POSITION 2 Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:18 volumio-bureau volumio[893]: info: No code Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:18 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:18 volumio-bureau volumio[893]: info: ------------------------------ 40ms Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioStop Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:19 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::play index 3 Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:19 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 3 Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:19 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 3 Nov 20 21:32:19 volumio-bureau volumio[893]: info: [1732134739498] ControllerTidal::clearAddPlayTrack Nov 20 21:32:19 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:19 volumio-bureau volumio[893]: info: Nov 20 21:32:19 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:19 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645356&soundQuality=HIGH" Nov 20 21:32:19 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:19 volumio-bureau volumio[893]: info: Nov 20 21:32:19 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:19 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:19 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645356&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:19 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645356&soundQuality=HIGH Nov 20 21:32:19 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:19 volumio-bureau volumio[893]: info: getStreamUrl took 419 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:19 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:19 volumio-bureau volumio[893]: info: Nov 20 21:32:19 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:19 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:19 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:19 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:19 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 3 Nov 20 21:32:19 volumio-bureau volumio[893]: 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":"393645356&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645356&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:19 volumio-bureau volumio[893]: verbose: CURRENT POSITION 3 Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:19 volumio-bureau volumio[893]: info: No code Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:19 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:19 volumio-bureau volumio[893]: info: ------------------------------ 38ms Nov 20 21:32:20 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::play index 4 Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:20 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 4 Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:20 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 4 Nov 20 21:32:20 volumio-bureau volumio[893]: info: [1732134740941] ControllerTidal::clearAddPlayTrack Nov 20 21:32:20 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:20 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:20 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:20 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:20 volumio-bureau volumio[893]: info: Nov 20 21:32:20 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:20 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:20 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:20 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645358&soundQuality=HIGH" Nov 20 21:32:20 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:20 volumio-bureau volumio[893]: info: Nov 20 21:32:20 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:20 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:20 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:20 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645358&soundQuality=HIGH" took 2 milliseconds Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:20 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:20 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:20 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:20 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645358&soundQuality=HIGH Nov 20 21:32:20 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:20 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:21 volumio-bureau volumio[893]: info: getStreamUrl took 386 milliseconds Nov 20 21:32:21 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:21 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:21 volumio-bureau volumio[893]: info: Nov 20 21:32:21 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:21 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:21 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:21 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:21 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:21 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:21 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:21 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 4 Nov 20 21:32:21 volumio-bureau volumio[893]: 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":"393645358&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645358&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:21 volumio-bureau volumio[893]: verbose: CURRENT POSITION 4 Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:21 volumio-bureau volumio[893]: info: No code Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:21 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:21 volumio-bureau volumio[893]: info: ------------------------------ 38ms Nov 20 21:32:22 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::play index 5 Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:22 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 5 Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:22 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 5 Nov 20 21:32:22 volumio-bureau volumio[893]: info: [1732134742348] ControllerTidal::clearAddPlayTrack Nov 20 21:32:22 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand stop took 0 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:22 volumio-bureau volumio[893]: info: Nov 20 21:32:22 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:22 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645360&soundQuality=HIGH" Nov 20 21:32:22 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:22 volumio-bureau volumio[893]: info: Nov 20 21:32:22 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:22 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:22 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645360&soundQuality=HIGH" took 2 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:22 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645360&soundQuality=HIGH Nov 20 21:32:22 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:22 volumio-bureau volumio[893]: info: getStreamUrl took 489 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:22 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:22 volumio-bureau volumio[893]: info: Nov 20 21:32:22 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:22 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:22 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 1 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:22 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:22 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 5 Nov 20 21:32:22 volumio-bureau volumio[893]: 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":"393645360&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645360&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:22 volumio-bureau volumio[893]: verbose: CURRENT POSITION 5 Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:22 volumio-bureau volumio[893]: info: No code Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:22 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:22 volumio-bureau volumio[893]: info: ------------------------------ 41ms Nov 20 21:32:23 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::play index 6 Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:23 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 6 Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:23 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 6 Nov 20 21:32:23 volumio-bureau volumio[893]: info: [1732134743857] ControllerTidal::clearAddPlayTrack Nov 20 21:32:23 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:23 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:23 volumio-bureau volumio[893]: info: sendMpdCommand stop took 0 milliseconds Nov 20 21:32:23 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:23 volumio-bureau volumio[893]: info: Nov 20 21:32:23 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:23 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:23 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:23 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645361&soundQuality=HIGH" Nov 20 21:32:23 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:23 volumio-bureau volumio[893]: info: Nov 20 21:32:23 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:23 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:23 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:23 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645361&soundQuality=HIGH" took 2 milliseconds Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:23 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:23 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:23 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:23 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645361&soundQuality=HIGH Nov 20 21:32:23 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:23 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:24 volumio-bureau volumio[893]: info: getStreamUrl took 388 milliseconds Nov 20 21:32:24 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:24 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:24 volumio-bureau volumio[893]: info: Nov 20 21:32:24 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:24 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:24 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:24 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:24 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 7 milliseconds Nov 20 21:32:24 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:24 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:24 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 6 Nov 20 21:32:24 volumio-bureau volumio[893]: 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":"393645361&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645361&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:24 volumio-bureau volumio[893]: verbose: CURRENT POSITION 6 Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:24 volumio-bureau volumio[893]: info: No code Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:24 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:24 volumio-bureau volumio[893]: info: ------------------------------ 50ms Nov 20 21:32:25 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::play index 7 Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:25 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 7 Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:25 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 7 Nov 20 21:32:25 volumio-bureau volumio[893]: info: [1732134745264] ControllerTidal::clearAddPlayTrack Nov 20 21:32:25 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand stop took 0 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:25 volumio-bureau volumio[893]: info: Nov 20 21:32:25 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:25 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand clear took 0 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645362&soundQuality=HIGH" Nov 20 21:32:25 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:25 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:25 volumio-bureau volumio[893]: info: Nov 20 21:32:25 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:25 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645362&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:25 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand play took 2 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645362&soundQuality=HIGH Nov 20 21:32:25 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:25 volumio-bureau volumio[893]: info: getStreamUrl took 361 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:25 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:25 volumio-bureau volumio[893]: info: Nov 20 21:32:25 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:25 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:25 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:25 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:25 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 7 Nov 20 21:32:25 volumio-bureau volumio[893]: 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":"393645362&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645362&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:25 volumio-bureau volumio[893]: verbose: CURRENT POSITION 7 Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:25 volumio-bureau volumio[893]: info: No code Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:25 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:25 volumio-bureau volumio[893]: info: ------------------------------ 38ms Nov 20 21:32:26 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::play index 8 Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:26 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 8 Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:26 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 8 Nov 20 21:32:26 volumio-bureau volumio[893]: info: [1732134746644] ControllerTidal::clearAddPlayTrack Nov 20 21:32:26 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:26 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:26 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:26 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:26 volumio-bureau volumio[893]: info: Nov 20 21:32:26 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:26 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:26 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:26 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645363&soundQuality=HIGH" Nov 20 21:32:26 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:26 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:26 volumio-bureau volumio[893]: info: Nov 20 21:32:26 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:26 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:26 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645363&soundQuality=HIGH" took 2 milliseconds Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:26 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:26 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:26 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:26 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645363&soundQuality=HIGH Nov 20 21:32:26 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:26 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:27 volumio-bureau volumio[893]: info: getStreamUrl took 367 milliseconds Nov 20 21:32:27 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:27 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:27 volumio-bureau volumio[893]: info: Nov 20 21:32:27 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:27 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:27 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:27 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:27 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:27 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:27 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:27 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 8 Nov 20 21:32:27 volumio-bureau volumio[893]: 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":"393645363&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645363&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:27 volumio-bureau volumio[893]: verbose: CURRENT POSITION 8 Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:27 volumio-bureau volumio[893]: info: No code Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:27 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:27 volumio-bureau volumio[893]: info: ------------------------------ 44ms Nov 20 21:32:28 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::play index 9 Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:28 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 9 Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:28 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 9 Nov 20 21:32:28 volumio-bureau volumio[893]: info: [1732134748033] ControllerTidal::clearAddPlayTrack Nov 20 21:32:28 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:28 volumio-bureau volumio[893]: info: Nov 20 21:32:28 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:28 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645364&soundQuality=HIGH" Nov 20 21:32:28 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:28 volumio-bureau volumio[893]: info: Nov 20 21:32:28 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:28 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:28 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645364&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:28 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645364&soundQuality=HIGH Nov 20 21:32:28 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:28 volumio-bureau volumio[893]: info: getStreamUrl took 506 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:28 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:28 volumio-bureau volumio[893]: info: Nov 20 21:32:28 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:28 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:28 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand status took 2 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:28 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:28 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 9 Nov 20 21:32:28 volumio-bureau volumio[893]: 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":"393645364&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645364&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:28 volumio-bureau volumio[893]: verbose: CURRENT POSITION 9 Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:28 volumio-bureau volumio[893]: info: No code Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:28 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:28 volumio-bureau volumio[893]: info: ------------------------------ 38ms Nov 20 21:32:29 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::play index 10 Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:29 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 10 Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:29 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 10 Nov 20 21:32:29 volumio-bureau volumio[893]: info: [1732134749557] ControllerTidal::clearAddPlayTrack Nov 20 21:32:29 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:29 volumio-bureau volumio[893]: info: Nov 20 21:32:29 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:29 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645365&soundQuality=HIGH" Nov 20 21:32:29 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:29 volumio-bureau volumio[893]: info: Nov 20 21:32:29 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:29 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:29 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645365&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:29 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand play took 0 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645365&soundQuality=HIGH Nov 20 21:32:29 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:29 volumio-bureau volumio[893]: info: getStreamUrl took 395 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:29 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:29 volumio-bureau volumio[893]: info: Nov 20 21:32:29 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:29 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:29 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand status took 4 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 3 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:29 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:29 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 10 Nov 20 21:32:29 volumio-bureau volumio[893]: 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":"393645365&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645365&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:29 volumio-bureau volumio[893]: verbose: CURRENT POSITION 10 Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:29 volumio-bureau volumio[893]: info: No code Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:29 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:30 volumio-bureau volumio[893]: info: ------------------------------ 39ms Nov 20 21:32:30 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::play index 11 Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:30 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 11 Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:30 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 11 Nov 20 21:32:30 volumio-bureau volumio[893]: info: [1732134750973] ControllerTidal::clearAddPlayTrack Nov 20 21:32:30 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:30 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:30 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:30 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:30 volumio-bureau volumio[893]: info: Nov 20 21:32:30 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:30 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:30 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:30 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645366&soundQuality=HIGH" Nov 20 21:32:30 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:30 volumio-bureau volumio[893]: info: ------------------------------ 2ms Nov 20 21:32:30 volumio-bureau volumio[893]: info: Nov 20 21:32:30 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:30 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:30 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645366&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:30 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:30 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:30 volumio-bureau volumio[893]: info: sendMpdCommand play took 1 milliseconds Nov 20 21:32:30 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645366&soundQuality=HIGH Nov 20 21:32:30 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:30 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:31 volumio-bureau volumio[893]: info: getStreamUrl took 374 milliseconds Nov 20 21:32:31 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:31 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:31 volumio-bureau volumio[893]: info: Nov 20 21:32:31 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:31 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:31 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:31 volumio-bureau volumio[893]: info: sendMpdCommand status took 5 milliseconds Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:31 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:31 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:31 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:31 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 11 Nov 20 21:32:31 volumio-bureau volumio[893]: 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":"393645366&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645366&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:31 volumio-bureau volumio[893]: verbose: CURRENT POSITION 11 Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:31 volumio-bureau volumio[893]: info: No code Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:31 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:31 volumio-bureau volumio[893]: info: ------------------------------ 46ms Nov 20 21:32:32 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::play index 12 Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:32 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 12 Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::startPlaybackTimer Nov 20 21:32:32 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 12 Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 20 21:32:32 volumio-bureau volumio[893]: info: [1732134752370] ControllerTidal::clearAddPlayTrack Nov 20 21:32:32 volumio-bureau volumio[893]: info: Getting stream with soundQuality HIGH Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand stop Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand stop took 1 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clear Nov 20 21:32:32 volumio-bureau volumio[893]: info: Nov 20 21:32:32 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:32 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand clear took 1 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645367&soundQuality=HIGH" Nov 20 21:32:32 volumio-bureau volumio[893]: error: updateQueue error: null Nov 20 21:32:32 volumio-bureau volumio[893]: info: Nov 20 21:32:32 volumio-bureau volumio[893]: ---------------------------- MPD announces system playlist update Nov 20 21:32:32 volumio-bureau volumio[893]: info: Ignoring MPD Status Update Nov 20 21:32:32 volumio-bureau volumio[893]: info: ------------------------------ 1ms Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/393645367&soundQuality=HIGH" took 1 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand play Nov 20 21:32:32 volumio-bureau volumio[893]: STREAMING PROXY: Handling url /?data=tidal://song/393645367&soundQuality=HIGH Nov 20 21:32:32 volumio-bureau volumio[893]: info: ------------------------------ 3ms Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand play took 2 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: info: Executing endpoint getStreamUrltidal Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Nov 20 21:32:32 volumio-bureau volumio[893]: info: getStreamUrl took 366 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: error: Failed to retrieve real stream URL Could not get TIDAL Stream URL: failed to get stream from provider tidal: rpc error: code = PermissionDenied desc = failed to get track playback info: got 401: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} Nov 20 21:32:32 volumio-bureau volumio[893]: 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 = PermissionDenied desc = failed to get track playback info: got 401: {\"status\":401,\"subStatus\":5003,\"userMessage\":\"Requested quality is not allowed in user's subscription\"}"} Nov 20 21:32:32 volumio-bureau volumio[893]: info: Nov 20 21:32:32 volumio-bureau volumio[893]: ---------------------------- MPD announces state update: player Nov 20 21:32:32 volumio-bureau volumio[893]: info: ControllerMpd::getState Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand status Nov 20 21:32:32 volumio-bureau volumio[893]: error: MPD Playback Error: Failed to decode stream, moving to next track Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand status took 3 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::parseState Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand clearerror took 2 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: ControllerMpd::parseTrackInfo Nov 20 21:32:32 volumio-bureau volumio[893]: info: ControllerMpd::pushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::servicePushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:32 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 12 Nov 20 21:32:32 volumio-bureau volumio[893]: 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":"393645367&soundQuality=HIGH","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/393645367&soundQuality=HIGH","trackType":"tidal"} Nov 20 21:32:32 volumio-bureau volumio[893]: verbose: CURRENT POSITION 12 Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::syncState stateService stop Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::syncState currentStatus stop Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:32 volumio-bureau volumio[893]: info: No code Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreStateMachine::pushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPushState Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output update for this device Nov 20 21:32:32 volumio-bureau volumio[893]: info: MRS: Pushing multiroomSync output Nov 20 21:32:32 volumio-bureau volumio[893]: info: ------------------------------ 32ms Nov 20 21:32:33 volumio-bureau volumio[893]: info: Moving to next track after playback error Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioPlay Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::play index 13 Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::play index undefined Nov 20 21:32:33 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:33 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 13 Nov 20 21:32:45 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioStop Nov 20 21:32:45 volumio-bureau volumio[893]: info: CoreStateMachine::stop Nov 20 21:32:45 volumio-bureau volumio[893]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 20 21:32:53 volumio-bureau nmbd[802]: [2024/11/20 21:32:53.489119, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 20 21:32:53 volumio-bureau nmbd[802]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.14 for name WORKGROUP<1d>. Nov 20 21:32:53 volumio-bureau nmbd[802]: This response was from IP 192.168.1.50, reporting an IP address of 192.168.1.50. Nov 20 21:32:57 volumio-bureau sudo[3664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 20 21:32:57 volumio-bureau sudo[3664]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 21:32:57 volumio-bureau sudo[3664]: pam_unix(sudo:session): session closed for user root Nov 20 21:32:57 volumio-bureau sudo[3667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 20 21:32:57 volumio-bureau sudo[3667]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 20 21:32:57 volumio-bureau sudo[3667]: pam_unix(sudo:session): session closed for user root Nov 20 21:32:57 volumio-bureau volumio[893]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.63 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetVisibleSources Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:58 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 20 21:32:58 volumio-bureau volumio[893]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 20 21:32:58 volumio-bureau volumio[893]: info: Received Get System Info Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 21:32:58 volumio-bureau volumio[893]: info: Discovery: Getting this device information Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:58 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:58 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:58 volumio-bureau volumio[893]: info: Listing playlists Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 20 21:32:58 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:58 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 20 21:32:59 volumio-bureau volumio[893]: info: Received Get System Info Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 21:32:59 volumio-bureau volumio[893]: info: Discovery: Getting this device information Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:32:59 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 21:32:59 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 20 21:33:00 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 20 21:33:00 volumio-bureau volumio[893]: info: Received Get System Info Nov 20 21:33:00 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 21:33:00 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 21:33:00 volumio-bureau volumio[893]: info: Discovery: Getting this device information Nov 20 21:33:00 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:33:00 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:33:00 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 21:33:07 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: Retrieving Cloud Streaming UI Nov 20 21:33:13 volumio-bureau volumio[893]: info: Getting Tidal Cloud Configuration Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: Getting Qobuz Cloud Configuration Nov 20 21:33:13 volumio-bureau volumio[893]: info: Asking plugin for UI Config Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: Getting Spotify Cloud Configuration Nov 20 21:33:13 volumio-bureau volumio[893]: info: Asking plugin for UI Config Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: Saving Spotify Acccount Nov 20 21:33:13 volumio-bureau volumio[893]: info: Got it Nov 20 21:33:13 volumio-bureau volumio[893]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 20 21:33:13 volumio-bureau volumio[893]: info: Got Tidal Cloud Configuration Nov 20 21:33:13 volumio-bureau volumio[893]: info: Got it Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Nov 20 21:33:13 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetBrowseSources Nov 20 21:33:14 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 20 21:33:14 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 20 21:33:15 volumio-bureau volumio[893]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 20 21:33:15 volumio-bureau volumio[893]: info: Received Get System Version Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 20 21:33:15 volumio-bureau volumio[893]: info: Received Get System Info Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 20 21:33:15 volumio-bureau volumio[893]: info: Discovery: Getting this device information Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:33:15 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:33:15 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 20 21:33:18 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 20 21:33:22 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 20 21:33:26 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 20 21:33:28 volumio-bureau volumio[893]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Nov 20 21:33:28 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Nov 20 21:33:28 volumio-bureau volumio[893]: info: Saving configuration Nov 20 21:33:28 volumio-bureau volumio[893]: info: Done Nov 20 21:33:30 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 20 21:33:30 volumio-bureau volumio[893]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Nov 20 21:33:30 volumio-bureau volumio[893]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Nov 20 21:33:30 volumio-bureau volumio[893]: info: Saving configuration Nov 20 21:33:30 volumio-bureau volumio[893]: info: Done Nov 20 21:33:34 volumio-bureau volumio[893]: info: CoreCommandRouter::volumioGetState Nov 20 21:33:34 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:33:38 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 0 Nov 20 21:33:38 volumio-bureau volumio[893]: info: CorePlayQueue::getTrack 1 Nov 20 21:33:38 volumio-bureau volumio[893]: info: Prefetching next song Nov 20 21:33:38 volumio-bureau volumio[893]: info: [1732134818388] ControllerTidal::prefetch Nov 20 21:33:38 volumio-bureau volumio[893]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 21:33:38 volumio-bureau volumio[893]: TypeError: Cannot read property 'type' of null Nov 20 21:33:38 volumio-bureau volumio[893]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:19246) Nov 20 21:33:38 volumio-bureau volumio[893]: at ControllerTidal.prefetch (/myvolumio/plugins/music_service/tidal/tidal_real:1:19741) Nov 20 21:33:38 volumio-bureau volumio[893]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:460:18) Nov 20 21:33:38 volumio-bureau volumio[893]: at listOnTimeout (internal/timers.js:554:17) Nov 20 21:33:38 volumio-bureau volumio[893]: at processTimers (internal/timers.js:497:7) Nov 20 21:33:38 volumio-bureau volumio[893]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 20 21:33:38 volumio-bureau sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-20 21:32 Nov 20 21:33:38 volumio-bureau sudo[3751]: 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"