-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2025-04-26 22:24:18 CST. -- Apr 26 22:23:00 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::play index 2 Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:00 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 2 Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:00 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 2 Apr 26 22:23:00 raspberry2024 volumio[844]: info: [1745677380122] ControllerTidal::clearAddPlayTrack Apr 26 22:23:00 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand stop took 2 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:00 raspberry2024 volumio[844]: info: Apr 26 22:23:00 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:00 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/346558881&soundQuality=HI_RES" Apr 26 22:23:00 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:00 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:00 raspberry2024 volumio[844]: info: Apr 26 22:23:00 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:00 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/346558881&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:00 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/346558881&soundQuality=HI_RES Apr 26 22:23:00 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:00 raspberry2024 volumio[844]: info: getStreamUrl took 790 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: 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"} Apr 26 22:23:00 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:00 raspberry2024 volumio[844]: info: Apr 26 22:23:00 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:00 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:00 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:00 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 2 Apr 26 22:23:00 raspberry2024 volumio[844]: 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":"346558881&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/346558881&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:00 raspberry2024 volumio[844]: verbose: CURRENT POSITION 2 Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: No code Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:00 raspberry2024 volumio[844]: info: ------------------------------ 22ms Apr 26 22:23:00 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:00 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:01 raspberry2024 volumio[844]: info: explodeTIDALUri took 7066 milliseconds Apr 26 22:23:01 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::play index 3 Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:01 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 3 Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:01 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 3 Apr 26 22:23:01 raspberry2024 volumio[844]: info: [1745677381947] ControllerTidal::clearAddPlayTrack Apr 26 22:23:01 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:01 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:01 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:01 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:01 raspberry2024 volumio[844]: info: Apr 26 22:23:01 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:01 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:01 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:01 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/105932445&soundQuality=HI_RES" Apr 26 22:23:01 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:01 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:01 raspberry2024 volumio[844]: info: Apr 26 22:23:01 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:01 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:01 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/105932445&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:01 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:01 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:01 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:01 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/105932445&soundQuality=HI_RES Apr 26 22:23:01 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:01 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:02 raspberry2024 volumio[844]: info: getStreamUrl took 784 milliseconds Apr 26 22:23:02 raspberry2024 volumio[844]: 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"} Apr 26 22:23:02 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:02 raspberry2024 volumio[844]: info: Apr 26 22:23:02 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:02 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:02 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:02 raspberry2024 volumio[844]: info: sendMpdCommand status took 5 milliseconds Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:02 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 4 milliseconds Apr 26 22:23:02 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:02 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 3 Apr 26 22:23:02 raspberry2024 volumio[844]: 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":"105932445&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105932445&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:02 raspberry2024 volumio[844]: verbose: CURRENT POSITION 3 Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: No code Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:02 raspberry2024 volumio[844]: info: ------------------------------ 34ms Apr 26 22:23:02 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:02 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:03 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::play index 4 Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:03 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 4 Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:03 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 4 Apr 26 22:23:03 raspberry2024 volumio[844]: info: [1745677383768] ControllerTidal::clearAddPlayTrack Apr 26 22:23:03 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:03 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:03 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:03 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:03 raspberry2024 volumio[844]: info: Apr 26 22:23:03 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:03 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:03 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:03 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/8021697&soundQuality=HI_RES" Apr 26 22:23:03 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:03 raspberry2024 volumio[844]: info: Apr 26 22:23:03 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:03 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:03 raspberry2024 volumio[844]: info: ------------------------------ 8ms Apr 26 22:23:03 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/8021697&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:03 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:03 raspberry2024 volumio[844]: info: ------------------------------ 4ms Apr 26 22:23:03 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/8021697&soundQuality=HI_RES Apr 26 22:23:03 raspberry2024 volumio[844]: info: sendMpdCommand play took 4 milliseconds Apr 26 22:23:03 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:03 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:04 raspberry2024 volumio[844]: info: getStreamUrl took 1127 milliseconds Apr 26 22:23:04 raspberry2024 volumio[844]: 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"} Apr 26 22:23:04 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:04 raspberry2024 volumio[844]: info: Apr 26 22:23:04 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:04 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:04 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:04 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:04 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:04 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:04 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 4 Apr 26 22:23:04 raspberry2024 volumio[844]: 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":"8021697&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/8021697&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:04 raspberry2024 volumio[844]: verbose: CURRENT POSITION 4 Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: No code Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:04 raspberry2024 volumio[844]: info: ------------------------------ 23ms Apr 26 22:23:04 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:04 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:05 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::play index 5 Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:05 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 5 Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:05 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 5 Apr 26 22:23:05 raspberry2024 volumio[844]: info: [1745677385935] ControllerTidal::clearAddPlayTrack Apr 26 22:23:05 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:05 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:05 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:05 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:05 raspberry2024 volumio[844]: info: Apr 26 22:23:05 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:05 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:05 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:05 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/339613148&soundQuality=HI_RES" Apr 26 22:23:05 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:05 raspberry2024 volumio[844]: info: Apr 26 22:23:05 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:05 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:05 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:05 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/339613148&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:05 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:05 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:05 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:05 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/339613148&soundQuality=HI_RES Apr 26 22:23:05 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:05 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:07 raspberry2024 volumio[844]: info: getStreamUrl took 1612 milliseconds Apr 26 22:23:07 raspberry2024 volumio[844]: 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"} Apr 26 22:23:07 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:07 raspberry2024 volumio[844]: info: Apr 26 22:23:07 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:07 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:07 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:07 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:07 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:07 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:07 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 5 Apr 26 22:23:07 raspberry2024 volumio[844]: 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":"339613148&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/339613148&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:07 raspberry2024 volumio[844]: verbose: CURRENT POSITION 5 Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: No code Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:07 raspberry2024 volumio[844]: info: ------------------------------ 23ms Apr 26 22:23:07 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:07 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:08 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::play index 6 Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:08 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 6 Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:08 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 6 Apr 26 22:23:08 raspberry2024 volumio[844]: info: [1745677388586] ControllerTidal::clearAddPlayTrack Apr 26 22:23:08 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:08 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:08 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:08 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:08 raspberry2024 volumio[844]: info: Apr 26 22:23:08 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:08 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:08 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:08 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/249553251&soundQuality=HI_RES" Apr 26 22:23:08 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:08 raspberry2024 volumio[844]: info: Apr 26 22:23:08 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:08 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:08 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:08 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/249553251&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:08 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:08 raspberry2024 volumio[844]: info: ------------------------------ 4ms Apr 26 22:23:08 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/249553251&soundQuality=HI_RES Apr 26 22:23:08 raspberry2024 volumio[844]: info: sendMpdCommand play took 3 milliseconds Apr 26 22:23:08 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:08 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:09 raspberry2024 volumio[844]: info: getStreamUrl took 1043 milliseconds Apr 26 22:23:09 raspberry2024 volumio[844]: 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"} Apr 26 22:23:09 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:09 raspberry2024 volumio[844]: info: Apr 26 22:23:09 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:09 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:09 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:09 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:09 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:09 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:09 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 6 Apr 26 22:23:09 raspberry2024 volumio[844]: 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":"249553251&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/249553251&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:09 raspberry2024 volumio[844]: verbose: CURRENT POSITION 6 Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: No code Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:09 raspberry2024 volumio[844]: info: ------------------------------ 38ms Apr 26 22:23:09 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:09 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:10 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::play index 7 Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:10 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 7 Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:10 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 7 Apr 26 22:23:10 raspberry2024 volumio[844]: info: [1745677390661] ControllerTidal::clearAddPlayTrack Apr 26 22:23:10 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:10 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:10 raspberry2024 volumio[844]: info: sendMpdCommand stop took 4 milliseconds Apr 26 22:23:10 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:10 raspberry2024 volumio[844]: info: Apr 26 22:23:10 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:10 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:10 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:10 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/120259270&soundQuality=HI_RES" Apr 26 22:23:10 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:10 raspberry2024 volumio[844]: info: Apr 26 22:23:10 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:10 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:10 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:10 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/120259270&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:10 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:10 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:10 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:10 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/120259270&soundQuality=HI_RES Apr 26 22:23:10 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:10 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:13 raspberry2024 volumio[844]: info: getStreamUrl took 2452 milliseconds Apr 26 22:23:13 raspberry2024 volumio[844]: 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"} Apr 26 22:23:13 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:13 raspberry2024 volumio[844]: info: Apr 26 22:23:13 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:13 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:13 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:13 raspberry2024 volumio[844]: info: sendMpdCommand status took 6 milliseconds Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:13 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 4 milliseconds Apr 26 22:23:13 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:13 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 7 Apr 26 22:23:13 raspberry2024 volumio[844]: 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":"120259270&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/120259270&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:13 raspberry2024 volumio[844]: verbose: CURRENT POSITION 7 Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: No code Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:13 raspberry2024 volumio[844]: info: ------------------------------ 25ms Apr 26 22:23:13 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:13 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:14 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::play index 8 Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:14 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 8 Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:14 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 8 Apr 26 22:23:14 raspberry2024 volumio[844]: info: [1745677394156] ControllerTidal::clearAddPlayTrack Apr 26 22:23:14 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:14 raspberry2024 volumio[844]: info: Apr 26 22:23:14 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:14 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand clear took 6 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/350657054&soundQuality=HI_RES" Apr 26 22:23:14 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:14 raspberry2024 volumio[844]: info: Apr 26 22:23:14 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:14 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:14 raspberry2024 volumio[844]: info: ------------------------------ 4ms Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/350657054&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:14 raspberry2024 volumio[844]: info: ------------------------------ 4ms Apr 26 22:23:14 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/350657054&soundQuality=HI_RES Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand play took 3 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 22:23:14 raspberry2024 volumio[844]: info: Discovery: Getting this device information Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioGetState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 22:23:14 raspberry2024 volumio[844]: info: getStreamUrl took 723 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: 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"} Apr 26 22:23:14 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:14 raspberry2024 volumio[844]: info: Apr 26 22:23:14 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:14 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:14 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:14 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 8 Apr 26 22:23:14 raspberry2024 volumio[844]: 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":"350657054&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/350657054&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:14 raspberry2024 volumio[844]: verbose: CURRENT POSITION 8 Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: No code Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:14 raspberry2024 volumio[844]: info: ------------------------------ 22ms Apr 26 22:23:14 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:14 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:15 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::play index 9 Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:15 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 9 Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:15 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 9 Apr 26 22:23:15 raspberry2024 volumio[844]: info: [1745677395918] ControllerTidal::clearAddPlayTrack Apr 26 22:23:15 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:15 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:15 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:15 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:15 raspberry2024 volumio[844]: info: Apr 26 22:23:15 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:15 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:15 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:15 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/232356591&soundQuality=HI_RES" Apr 26 22:23:15 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:15 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:15 raspberry2024 volumio[844]: info: Apr 26 22:23:15 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:15 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:15 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/232356591&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:15 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:15 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:15 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/232356591&soundQuality=HI_RES Apr 26 22:23:15 raspberry2024 volumio[844]: info: sendMpdCommand play took 3 milliseconds Apr 26 22:23:15 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:15 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:17 raspberry2024 volumio[844]: info: getStreamUrl took 1702 milliseconds Apr 26 22:23:17 raspberry2024 volumio[844]: 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"} Apr 26 22:23:17 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:17 raspberry2024 volumio[844]: info: Apr 26 22:23:17 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:17 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:17 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:17 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:17 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:17 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:17 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 9 Apr 26 22:23:17 raspberry2024 volumio[844]: 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":"232356591&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/232356591&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:17 raspberry2024 volumio[844]: verbose: CURRENT POSITION 9 Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: No code Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:17 raspberry2024 volumio[844]: info: ------------------------------ 27ms Apr 26 22:23:17 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:17 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:18 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::play index 10 Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:18 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 10 Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:18 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 10 Apr 26 22:23:18 raspberry2024 volumio[844]: info: [1745677398657] ControllerTidal::clearAddPlayTrack Apr 26 22:23:18 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:18 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:18 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:18 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:18 raspberry2024 volumio[844]: info: Apr 26 22:23:18 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:18 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:18 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:18 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/220343040&soundQuality=HI_RES" Apr 26 22:23:18 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:18 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:18 raspberry2024 volumio[844]: info: Apr 26 22:23:18 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:18 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:18 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/220343040&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:18 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:18 raspberry2024 volumio[844]: info: ------------------------------ 4ms Apr 26 22:23:18 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/220343040&soundQuality=HI_RES Apr 26 22:23:18 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:18 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:18 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:19 raspberry2024 volumio[844]: info: getStreamUrl took 1040 milliseconds Apr 26 22:23:19 raspberry2024 volumio[844]: 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"} Apr 26 22:23:19 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:19 raspberry2024 volumio[844]: info: Apr 26 22:23:19 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:19 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:19 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:19 raspberry2024 volumio[844]: info: sendMpdCommand status took 3 milliseconds Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:19 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:19 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:19 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 10 Apr 26 22:23:19 raspberry2024 volumio[844]: 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":"220343040&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/220343040&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:19 raspberry2024 volumio[844]: verbose: CURRENT POSITION 10 Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: No code Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:19 raspberry2024 volumio[844]: info: ------------------------------ 30ms Apr 26 22:23:19 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:19 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:20 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::play index 11 Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:20 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 11 Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:20 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 11 Apr 26 22:23:20 raspberry2024 volumio[844]: info: [1745677400730] ControllerTidal::clearAddPlayTrack Apr 26 22:23:20 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:20 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:20 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:20 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:20 raspberry2024 volumio[844]: info: Apr 26 22:23:20 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:20 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:20 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:20 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224916295&soundQuality=HI_RES" Apr 26 22:23:20 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:20 raspberry2024 volumio[844]: info: Apr 26 22:23:20 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:20 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:20 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:20 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/224916295&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:20 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:20 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:20 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:20 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/224916295&soundQuality=HI_RES Apr 26 22:23:20 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:20 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:21 raspberry2024 volumio[844]: info: getStreamUrl took 743 milliseconds Apr 26 22:23:21 raspberry2024 volumio[844]: 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"} Apr 26 22:23:21 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:21 raspberry2024 volumio[844]: info: Apr 26 22:23:21 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:21 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:21 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:21 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:21 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:21 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:21 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 11 Apr 26 22:23:21 raspberry2024 volumio[844]: 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":"224916295&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/224916295&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:21 raspberry2024 volumio[844]: verbose: CURRENT POSITION 11 Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: No code Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:21 raspberry2024 volumio[844]: info: ------------------------------ 21ms Apr 26 22:23:21 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:21 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:22 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::play index 12 Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:22 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 12 Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:22 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 12 Apr 26 22:23:22 raspberry2024 volumio[844]: info: [1745677402507] ControllerTidal::clearAddPlayTrack Apr 26 22:23:22 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:22 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:22 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:22 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:22 raspberry2024 volumio[844]: info: Apr 26 22:23:22 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:22 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:22 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:22 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/319122597&soundQuality=HI_RES" Apr 26 22:23:22 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:22 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:22 raspberry2024 volumio[844]: info: Apr 26 22:23:22 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:22 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:22 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/319122597&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:22 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:22 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:22 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:22 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/319122597&soundQuality=HI_RES Apr 26 22:23:22 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:22 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:24 raspberry2024 volumio[844]: info: getStreamUrl took 2193 milliseconds Apr 26 22:23:24 raspberry2024 volumio[844]: 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"} Apr 26 22:23:24 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:24 raspberry2024 volumio[844]: info: Apr 26 22:23:24 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:24 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:24 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:24 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:24 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 5 milliseconds Apr 26 22:23:24 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:24 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 12 Apr 26 22:23:24 raspberry2024 volumio[844]: 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":"319122597&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/319122597&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:24 raspberry2024 volumio[844]: verbose: CURRENT POSITION 12 Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: No code Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:24 raspberry2024 volumio[844]: info: ------------------------------ 27ms Apr 26 22:23:24 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:24 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:25 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::play index 13 Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:25 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 13 Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:25 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 13 Apr 26 22:23:25 raspberry2024 volumio[844]: info: [1745677405734] ControllerTidal::clearAddPlayTrack Apr 26 22:23:25 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:25 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:25 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:25 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:25 raspberry2024 volumio[844]: info: Apr 26 22:23:25 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:25 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:25 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:25 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77615078&soundQuality=HI_RES" Apr 26 22:23:25 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:25 raspberry2024 volumio[844]: info: Apr 26 22:23:25 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:25 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:25 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:25 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77615078&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:25 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:25 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:25 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:25 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/77615078&soundQuality=HI_RES Apr 26 22:23:25 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:25 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:26 raspberry2024 volumio[844]: info: getStreamUrl took 727 milliseconds Apr 26 22:23:26 raspberry2024 volumio[844]: 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"} Apr 26 22:23:26 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:26 raspberry2024 volumio[844]: info: Apr 26 22:23:26 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:26 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:26 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:26 raspberry2024 volumio[844]: info: sendMpdCommand status took 3 milliseconds Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:26 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:26 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:26 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 13 Apr 26 22:23:26 raspberry2024 volumio[844]: 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":"77615078&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77615078&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:26 raspberry2024 volumio[844]: verbose: CURRENT POSITION 13 Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: No code Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:26 raspberry2024 volumio[844]: info: ------------------------------ 21ms Apr 26 22:23:26 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:26 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:27 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::play index 14 Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:27 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 14 Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:27 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 14 Apr 26 22:23:27 raspberry2024 volumio[844]: info: [1745677407492] ControllerTidal::clearAddPlayTrack Apr 26 22:23:27 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:27 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:27 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:27 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:27 raspberry2024 volumio[844]: info: Apr 26 22:23:27 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:27 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:27 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:27 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/80593718&soundQuality=HI_RES" Apr 26 22:23:27 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:27 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:27 raspberry2024 volumio[844]: info: Apr 26 22:23:27 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:27 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:27 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/80593718&soundQuality=HI_RES" took 3 milliseconds Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:27 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:27 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:27 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:27 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/80593718&soundQuality=HI_RES Apr 26 22:23:27 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:27 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:28 raspberry2024 volumio[844]: info: getStreamUrl took 720 milliseconds Apr 26 22:23:28 raspberry2024 volumio[844]: 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"} Apr 26 22:23:28 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:28 raspberry2024 volumio[844]: info: Apr 26 22:23:28 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:28 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:28 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:28 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:28 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:28 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:28 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 14 Apr 26 22:23:28 raspberry2024 volumio[844]: 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":"80593718&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/80593718&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:28 raspberry2024 volumio[844]: verbose: CURRENT POSITION 14 Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: No code Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:28 raspberry2024 volumio[844]: info: ------------------------------ 27ms Apr 26 22:23:28 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:28 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:29 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::play index 15 Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:29 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 15 Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:29 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 15 Apr 26 22:23:29 raspberry2024 volumio[844]: info: [1745677409244] ControllerTidal::clearAddPlayTrack Apr 26 22:23:29 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:29 raspberry2024 volumio[844]: info: Apr 26 22:23:29 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:29 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/220340852&soundQuality=HI_RES" Apr 26 22:23:29 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:29 raspberry2024 volumio[844]: info: Apr 26 22:23:29 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:29 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:29 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/220340852&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:29 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand play took 2 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/220340852&soundQuality=HI_RES Apr 26 22:23:29 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:29 raspberry2024 volumio[844]: info: getStreamUrl took 673 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: 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"} Apr 26 22:23:29 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:29 raspberry2024 volumio[844]: info: Apr 26 22:23:29 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:29 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:29 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand status took 5 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:29 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 15 Apr 26 22:23:29 raspberry2024 volumio[844]: 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":"220340852&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/220340852&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:29 raspberry2024 volumio[844]: verbose: CURRENT POSITION 15 Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: No code Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:29 raspberry2024 volumio[844]: info: ------------------------------ 29ms Apr 26 22:23:29 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:29 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 26 22:23:30 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::play index 16 Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:30 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 16 Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:30 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 16 Apr 26 22:23:30 raspberry2024 volumio[844]: info: [1745677410960] ControllerTidal::clearAddPlayTrack Apr 26 22:23:30 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:30 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:30 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:30 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:30 raspberry2024 volumio[844]: info: Apr 26 22:23:30 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:30 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:30 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:30 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12397670&soundQuality=HI_RES" Apr 26 22:23:30 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:30 raspberry2024 volumio[844]: info: Apr 26 22:23:30 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:30 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:30 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:30 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12397670&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:30 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:30 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/12397670&soundQuality=HI_RES Apr 26 22:23:30 raspberry2024 volumio[844]: info: ------------------------------ 8ms Apr 26 22:23:30 raspberry2024 volumio[844]: info: sendMpdCommand play took 6 milliseconds Apr 26 22:23:30 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:30 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:31 raspberry2024 volumio[844]: info: getStreamUrl took 748 milliseconds Apr 26 22:23:31 raspberry2024 volumio[844]: 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"} Apr 26 22:23:31 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:31 raspberry2024 volumio[844]: info: Apr 26 22:23:31 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:31 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:31 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:31 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:31 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:31 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:31 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 16 Apr 26 22:23:31 raspberry2024 volumio[844]: 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":"12397670&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/12397670&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:31 raspberry2024 volumio[844]: verbose: CURRENT POSITION 16 Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: No code Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:31 raspberry2024 volumio[844]: info: ------------------------------ 22ms Apr 26 22:23:31 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:31 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:32 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::play index 17 Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:32 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 17 Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:32 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 17 Apr 26 22:23:32 raspberry2024 volumio[844]: info: [1745677412740] ControllerTidal::clearAddPlayTrack Apr 26 22:23:32 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:32 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:32 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:32 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:32 raspberry2024 volumio[844]: info: Apr 26 22:23:32 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:32 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:32 raspberry2024 volumio[844]: info: sendMpdCommand clear took 2 milliseconds Apr 26 22:23:32 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/232125816&soundQuality=HI_RES" Apr 26 22:23:32 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:32 raspberry2024 volumio[844]: info: Apr 26 22:23:32 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:32 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:32 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:32 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/232125816&soundQuality=HI_RES" took 2 milliseconds Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:32 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:32 raspberry2024 volumio[844]: info: ------------------------------ 3ms Apr 26 22:23:32 raspberry2024 volumio[844]: info: sendMpdCommand play took 1 milliseconds Apr 26 22:23:32 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/232125816&soundQuality=HI_RES Apr 26 22:23:32 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:32 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:33 raspberry2024 volumio[844]: info: getStreamUrl took 839 milliseconds Apr 26 22:23:33 raspberry2024 volumio[844]: 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"} Apr 26 22:23:33 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:33 raspberry2024 volumio[844]: info: Apr 26 22:23:33 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:33 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:33 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:33 raspberry2024 volumio[844]: info: sendMpdCommand status took 4 milliseconds Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:33 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 2 milliseconds Apr 26 22:23:33 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:33 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 17 Apr 26 22:23:33 raspberry2024 volumio[844]: 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":"232125816&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/232125816&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:33 raspberry2024 volumio[844]: verbose: CURRENT POSITION 17 Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: No code Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:33 raspberry2024 volumio[844]: info: ------------------------------ 26ms Apr 26 22:23:33 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:33 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:33 raspberry2024 volumio[844]: info: browseTIDALUri took 3268 milliseconds Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/335333640 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/335650616 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/334049783 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/332049199 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/330548184 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/329113038 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/330312069 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/332264081 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/331342906 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/332266953 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/331224334 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/327728123 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/328975778 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/312147159 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/329113028 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/329348484 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/329348486 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/328068644 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319476059 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/328066182 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/328082116 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/326452015 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/326452013 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/311143223 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/326455932 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/326440207 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/325064392 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/324631182 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/323734444 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319556385 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/325078756 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/322914943 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/322929087 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319810595 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/322897993 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/322914940 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/321524261 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/321848792 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/313174968 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/321521933 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/317167187 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/320518634 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/318379597 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/320187294 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/317325473 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319789317 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319063540 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319297416 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319063538 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319297535 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/319297520 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/318015956 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/311633306 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/317691525 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/317694028 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/310100975 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/316412719 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/316412723 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/316409557 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309734873 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/311143221 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/315160661 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/315171191 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/313181435 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/314548593 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/313950349 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/313351898 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309183586 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/312770822 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309914961 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/304319841 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/311544119 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309125392 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/310100972 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/307185197 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/307410676 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/310295775 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/307123101 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309382343 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309071283 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/301766068 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/309071271 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/307597857 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/296563053 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/304074016 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/300952028 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/306814173 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/301726148 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/305805910 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/298716567 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/301722502 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/302312429 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/304819539 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/304820963 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/305095774 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/300994797 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/303656186 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/300892195 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/303658489 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Preloading song: tidal://song/283643590 Apr 26 22:23:33 raspberry2024 volumio[844]: info: Exploding uri tidal://song/335333640 in service tidal Apr 26 22:23:33 raspberry2024 volumio[844]: info: Exploding uri tidal://song/335650616 in service tidal Apr 26 22:23:33 raspberry2024 volumio[844]: info: Exploding uri tidal://song/334049783 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/332049199 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/330548184 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/329113038 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/330312069 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/332264081 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/331342906 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/332266953 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/331224334 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/327728123 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/328975778 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/312147159 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/329113028 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: explodeTIDALUri took 757 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/329348484 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::play index 18 Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:34 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 18 Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:34 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 18 Apr 26 22:23:34 raspberry2024 volumio[844]: info: [1745677414621] ControllerTidal::clearAddPlayTrack Apr 26 22:23:34 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:34 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:34 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:34 raspberry2024 volumio[844]: info: Apr 26 22:23:34 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:34 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:34 raspberry2024 volumio[844]: info: sendMpdCommand clear took 6 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12631512&soundQuality=HI_RES" Apr 26 22:23:34 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:34 raspberry2024 volumio[844]: info: Apr 26 22:23:34 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:34 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:34 raspberry2024 volumio[844]: info: ------------------------------ 5ms Apr 26 22:23:34 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/12631512&soundQuality=HI_RES" took 5 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:34 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:34 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:34 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/12631512&soundQuality=HI_RES Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/329348486 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: sendMpdCommand play took 34 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: explodeTIDALUri took 738 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/328068644 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319476059 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: explodeTIDALUri took 752 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/328066182 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/328082116 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/326452015 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: explodeTIDALUri took 773 milliseconds Apr 26 22:23:34 raspberry2024 volumio[844]: info: Exploding uri tidal://song/326452013 in service tidal Apr 26 22:23:34 raspberry2024 volumio[844]: info: explodeTIDALUri took 1061 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/311143223 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 821 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 735 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/326455932 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 711 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 683 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/326440207 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 1035 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 878 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/325064392 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 690 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/324631182 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/323734444 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 837 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319556385 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/325078756 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 781 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/322914943 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/322929087 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319810595 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/322897993 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 930 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 864 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: getStreamUrl took 875 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: 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"} Apr 26 22:23:35 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/322914940 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 773 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Apr 26 22:23:35 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:35 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/321524261 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:35 raspberry2024 volumio[844]: info: sendMpdCommand status took 51 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 1791 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 973 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 1021 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/321848792 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/313174968 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 64 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 63 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:35 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 18 Apr 26 22:23:35 raspberry2024 volumio[844]: 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":"12631512&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/12631512&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:35 raspberry2024 volumio[844]: verbose: CURRENT POSITION 18 Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: No code Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:35 raspberry2024 volumio[844]: info: ------------------------------ 133ms Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 867 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:35 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 859 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/321521933 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/317167187 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 806 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/320518634 in service tidal Apr 26 22:23:35 raspberry2024 volumio[844]: info: explodeTIDALUri took 888 milliseconds Apr 26 22:23:35 raspberry2024 volumio[844]: info: Exploding uri tidal://song/318379597 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/320187294 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/317325473 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319789317 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 789 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 859 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319063540 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319297416 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 885 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319063538 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 959 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 914 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 1194 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319297535 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319297520 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/318015956 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 941 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/311633306 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 925 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/317691525 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 891 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/317694028 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 867 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 786 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 704 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310100975 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 841 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/316412719 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 744 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/316412723 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 804 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::play index 19 Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:36 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 19 Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:36 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 19 Apr 26 22:23:36 raspberry2024 volumio[844]: info: [1745677416742] ControllerTidal::clearAddPlayTrack Apr 26 22:23:36 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:36 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:36 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:36 raspberry2024 volumio[844]: info: Apr 26 22:23:36 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:36 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:36 raspberry2024 volumio[844]: info: sendMpdCommand clear took 1 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/268119044&soundQuality=HI_RES" Apr 26 22:23:36 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:36 raspberry2024 volumio[844]: info: Apr 26 22:23:36 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:36 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:36 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:36 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/268119044&soundQuality=HI_RES" took 1 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:36 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:36 raspberry2024 volumio[844]: info: ------------------------------ 2ms Apr 26 22:23:36 raspberry2024 volumio[844]: info: sendMpdCommand play took 1 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/268119044&soundQuality=HI_RES Apr 26 22:23:36 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/316409557 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 781 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309734873 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/311143221 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 837 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: explodeTIDALUri took 2352 milliseconds Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/315160661 in service tidal Apr 26 22:23:36 raspberry2024 volumio[844]: info: Exploding uri tidal://song/315171191 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 1844 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 2149 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/313181435 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/314548593 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 895 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/313950349 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/313351898 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 783 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309183586 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 766 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 740 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/312770822 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 1048 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 737 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309914961 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 910 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/304319841 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/311544119 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309125392 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 1203 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 837 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310100972 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307185197 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 1173 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 1768 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307410676 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310295775 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 975 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307123101 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309382343 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309071283 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 845 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 786 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301766068 in service tidal Apr 26 22:23:37 raspberry2024 volumio[844]: info: explodeTIDALUri took 713 milliseconds Apr 26 22:23:37 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309071271 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307597857 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296563053 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 765 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 815 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 850 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/304074016 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 1220 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300952028 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 746 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/306814173 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 843 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301726148 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/305805910 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/298716567 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::ClearQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::clearPlayQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::saveQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::addQueueItems Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::addQueueItems Apr 26 22:23:38 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/335333640 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/335333640 Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::saveQueue Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::updateTrackBlock Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::getTrackBlock Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::play index 0 Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::addQueueItems Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::addQueueItems Apr 26 22:23:38 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/335650616 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/335650616 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/334049783 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/334049783 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/332049199 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/332049199 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/330548184 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/330548184 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/329113038 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/329113038 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/330312069 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/330312069 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/332264081 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/332264081 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/331342906 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/331342906 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/332266953 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/332266953 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/331224334 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/331224334 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/327728123 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/327728123 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/328975778 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/328975778 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/312147159 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/312147159 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/329113028 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/329113028 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/329348484 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/329348484 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/329348486 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/329348486 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/328068644 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/328068644 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319476059 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319476059 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/328066182 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/328066182 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/328082116 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/328082116 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/326452015 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/326452015 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/326452013 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/326452013 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/311143223 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/311143223 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/326455932 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/326455932 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/326440207 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/326440207 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/325064392 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/325064392 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/324631182 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/324631182 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/323734444 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/323734444 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319556385 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319556385 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/325078756 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/325078756 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/322914943 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/322914943 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/322929087 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/322929087 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319810595 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319810595 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/322897993 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/322897993 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/322914940 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/322914940 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/321524261 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/321524261 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/321848792 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/321848792 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/313174968 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/313174968 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/321521933 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/321521933 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/317167187 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/317167187 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/320518634 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/320518634 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/318379597 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/318379597 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/320187294 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/320187294 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/317325473 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/317325473 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319789317 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/319789317 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319063540 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319063540 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319297416 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319297416 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319063538 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319063538 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319297535 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319297535 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/319297520 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/319297520 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/318015956 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/318015956 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/311633306 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/311633306 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/317691525 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/317691525 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/317694028 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/317694028 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/310100975 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310100975 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/316412719 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/316412719 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/316412723 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/316412723 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/316409557 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/316409557 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309734873 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309734873 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/311143221 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/311143221 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/315160661 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/315160661 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/315171191 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/315171191 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/313181435 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/313181435 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/314548593 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/314548593 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/313950349 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/313950349 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/313351898 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/313351898 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309183586 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/309183586 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/312770822 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/312770822 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309914961 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309914961 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/304319841 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/304319841 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/311544119 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Using cached record of: tidal://song/311544119 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309125392 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309125392 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/310100972 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310100972 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/307185197 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307185197 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/307410676 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307410676 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/310295775 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/310295775 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/307123101 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307123101 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309382343 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309382343 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309071283 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309071283 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301766068 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301766068 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/309071271 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/309071271 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/307597857 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/307597857 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296563053 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296563053 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/304074016 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/304074016 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300952028 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300952028 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/306814173 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/306814173 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301726148 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301726148 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/305805910 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/305805910 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/298716567 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/298716567 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301722502 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301722502 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/302312429 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/302312429 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/304819539 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/304819539 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/304820963 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/304820963 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/305095774 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/305095774 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300994797 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300994797 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/303656186 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/303656186 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300892195 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300892195 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/303658489 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/303658489 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/283643590 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/283643590 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/303071431 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/303071431 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/302208895 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/302208895 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/299138311 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/299138311 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/302312426 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/302312426 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/302516603 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/302516603 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/298686563 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/298686563 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301322236 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301322236 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296960947 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296960947 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296563046 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296563046 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301317087 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301317087 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/301320786 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/301320786 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300185816 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300185816 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/299484380 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/299484380 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300185814 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300185814 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/300187236 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/300187236 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/299330798 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/299330798 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/299078845 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/299078845 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296110753 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296110753 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/299076493 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/299076493 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296271781 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296271781 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294220240 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294220240 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294635621 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294635621 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/295888962 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/295888962 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294221325 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294221325 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/292285770 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/292285770 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296902192 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296902192 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/292515752 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/292515752 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/292872439 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/292872439 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/296283349 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/296283349 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/295698831 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/295698831 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/295973003 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/295973003 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/295697373 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/295697373 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/286293180 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/286293180 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/295699908 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/295699908 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294523588 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294523588 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294826996 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294826996 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294123580 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294123580 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/294523672 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/294523672 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/293115723 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/293115723 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291790888 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291790888 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291082773 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291082773 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/289043587 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/289043587 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291525883 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291525883 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291792808 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291792808 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/288730565 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/288730565 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/284013422 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/284013422 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291781335 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291781335 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/286289583 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/286289583 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/291027027 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/291027027 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/284680485 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/284680485 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/286232838 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/286232838 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/290605675 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/290605675 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/288794443 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/288794443 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/289154006 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/289154006 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/289592491 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/289592491 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/288911362 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/288911362 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/288396780 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/288396780 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/287843005 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/287843005 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/287841484 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/287841484 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/280229291 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/280229291 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/281116287 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/281116287 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/287843031 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/287843031 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/285938732 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/285938732 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/281572757 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/281572757 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/286316246 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/286316246 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/286815761 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/286815761 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/280014875 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/280014875 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/280476840 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/280476840 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/284660443 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/284660443 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/285398690 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/285398690 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/284676738 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/284676738 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/277807038 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/277807038 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/281615205 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/281615205 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/283671188 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/283671188 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/282322641 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/282322641 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279640194 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279640194 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279259511 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279259511 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279964256 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279964256 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/281061005 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/281061005 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/278466592 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/278466592 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/281061011 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/281061011 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279810024 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279810024 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279798830 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279798830 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/279396959 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/279396959 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/273204335 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/273204335 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/277813732 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/277813732 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/278664542 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/278664542 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/278681675 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/278681675 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/277807032 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/277807032 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/266800688 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/266800688 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/277283369 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/277283369 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276424141 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276424141 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276512546 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276512546 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/275762954 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/275762954 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276408383 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276408383 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276031176 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276031176 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274958311 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274958311 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276031178 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276031178 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/276032845 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/276032845 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274858061 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274858061 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274870721 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274870721 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274868915 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274868915 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274431366 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274431366 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/274657767 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/274657767 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/270026938 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/270026938 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/273491589 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/273491589 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/271023826 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/271023826 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/273672502 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/273672502 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/272189932 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/272189932 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/272473367 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/272473367 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/272471161 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/272471161 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/271201561 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/271201561 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/266800662 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/266800662 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/271191769 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/271191769 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/267862226 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/267862226 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/271191765 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/271191765 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: Adding Item to queue: tidal://song/270127891 Apr 26 22:23:38 raspberry2024 volumio[844]: info: Exploding uri tidal://song/270127891 in service tidal Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 0 Apr 26 22:23:38 raspberry2024 volumio[844]: info: CoreStateMachine::startPlaybackTimer Apr 26 22:23:38 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 0 Apr 26 22:23:38 raspberry2024 volumio[844]: info: [1745677418664] ControllerTidal::clearAddPlayTrack Apr 26 22:23:38 raspberry2024 volumio[844]: info: Getting stream with soundQuality HI_RES Apr 26 22:23:38 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand stop Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 980 milliseconds Apr 26 22:23:38 raspberry2024 volumio[844]: info: explodeTIDALUri took 1814 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1789 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1846 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 2010 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1782 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1910 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1738 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 2141 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 1695 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 2032 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: Apr 26 22:23:39 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand stop took 1011 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clear Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 3068 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: getStreamUrl took 2923 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: 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"} Apr 26 22:23:39 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 2701 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 2641 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 3071 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: Apr 26 22:23:39 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:39 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand status took 94 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand clear took 93 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/335333640&soundQuality=HI_RES" Apr 26 22:23:39 raspberry2024 volumio[844]: error: updateQueue error: null Apr 26 22:23:39 raspberry2024 volumio[844]: info: Apr 26 22:23:39 raspberry2024 volumio[844]: ---------------------------- MPD announces system playlist update Apr 26 22:23:39 raspberry2024 volumio[844]: info: Ignoring MPD Status Update Apr 26 22:23:39 raspberry2024 volumio[844]: info: ------------------------------ 81ms Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 79 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/335333640&soundQuality=HI_RES" took 79 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:39 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 26 22:23:39 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand play Apr 26 22:23:39 raspberry2024 volumio[844]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 26 22:23:39 raspberry2024 volumio[844]: info: ------------------------------ 178ms Apr 26 22:23:39 raspberry2024 volumio[844]: info: ------------------------------ 6ms Apr 26 22:23:39 raspberry2024 volumio[844]: info: sendMpdCommand play took 4 milliseconds Apr 26 22:23:39 raspberry2024 volumio[844]: STREAMING PROXY: Handling url /?data=tidal://song/335333640&soundQuality=HI_RES Apr 26 22:23:39 raspberry2024 volumio[844]: info: Executing endpoint getStreamUrltidal Apr 26 22:23:39 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 26 22:23:39 raspberry2024 volumio[844]: info: explodeTIDALUri took 3813 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2071 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2540 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 3555 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2463 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2468 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2492 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2491 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2513 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2468 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2458 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2533 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2488 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2501 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2478 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2527 milliseconds Apr 26 22:23:40 raspberry2024 volumio[844]: info: explodeTIDALUri took 2537 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 2951 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 2791 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 2713 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3032 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3082 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3062 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3195 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3188 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3233 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3285 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3188 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3207 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3165 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3211 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 2978 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3173 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3180 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3190 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3190 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3207 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3221 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3265 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3235 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3216 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3461 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3231 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3225 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3659 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3276 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3293 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3355 milliseconds Apr 26 22:23:41 raspberry2024 volumio[844]: info: explodeTIDALUri took 3503 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3938 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3974 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3978 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3983 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3997 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 4025 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 4013 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3840 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3829 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3843 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3828 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 4119 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3873 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3887 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3970 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 3894 milliseconds Apr 26 22:23:42 raspberry2024 volumio[844]: info: explodeTIDALUri took 4133 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4475 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4796 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4545 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4550 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4553 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4577 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4548 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4584 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4696 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4725 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4709 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4711 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4717 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4757 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4710 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4730 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4750 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4605 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4589 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: getStreamUrl took 3383 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: 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"} Apr 26 22:23:43 raspberry2024 volumio[844]: 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\"}"} Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4620 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4638 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4645 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4680 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4886 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4727 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4885 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: Apr 26 22:23:43 raspberry2024 volumio[844]: ---------------------------- MPD announces state update: player Apr 26 22:23:43 raspberry2024 volumio[844]: info: ControllerMpd::getState Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand status Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4877 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: error: MPD Playback Error: Failed to decode stream, moving to next track Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 26 22:23:43 raspberry2024 volumio[844]: info: sendMpdCommand status took 10 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: ControllerMpd::parseState Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4884 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4915 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4879 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: sendMpdCommand clearerror took 19 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: ControllerMpd::parseTrackInfo Apr 26 22:23:43 raspberry2024 volumio[844]: info: ControllerMpd::pushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreCommandRouter::servicePushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 0 Apr 26 22:23:43 raspberry2024 volumio[844]: 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":"335333640&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/335333640&soundQuality=HI_RES","trackType":"tidal"} Apr 26 22:23:43 raspberry2024 volumio[844]: verbose: CURRENT POSITION 0 Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreStateMachine::syncState stateService stop Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreStateMachine::syncState currentStatus stop Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: No code Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreStateMachine::pushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPushState Apr 26 22:23:43 raspberry2024 volumio[844]: info: ------------------------------ 45ms Apr 26 22:23:43 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:43 raspberry2024 volumio[844]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.KnhMpHhvMWQiIZaoEL0k0x8yuzw1.b9e91fb1c09211d112ec4b03b3a8c2a6.state.status' Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4912 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4952 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4955 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4933 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 4961 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 5003 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 5018 milliseconds Apr 26 22:23:43 raspberry2024 volumio[844]: info: explodeTIDALUri took 5143 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5511 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5534 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5639 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5667 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5659 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5666 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5807 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5739 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5739 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5711 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5717 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5721 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5731 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 5729 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: Moving to next track after playback error Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioPlay Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::play index 1 Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::stop Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::play index undefined Apr 26 22:23:44 raspberry2024 volumio[844]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 22:23:44 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 1 Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6118 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6211 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6249 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6332 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6326 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6313 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6391 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6453 milliseconds Apr 26 22:23:44 raspberry2024 volumio[844]: info: explodeTIDALUri took 6421 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6471 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6496 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6492 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6602 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6594 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6593 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6597 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6613 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 22:23:45 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 22:23:45 raspberry2024 volumio[844]: info: Discovery: Getting this device information Apr 26 22:23:45 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioGetState Apr 26 22:23:45 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 0 Apr 26 22:23:45 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6612 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6700 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6693 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6702 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6729 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6712 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6729 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6775 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6735 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6806 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6852 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6813 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6919 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6930 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 6968 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 7061 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 7125 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 7192 milliseconds Apr 26 22:23:45 raspberry2024 volumio[844]: info: explodeTIDALUri took 7366 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 7654 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 7710 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 7766 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 7846 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 8065 milliseconds Apr 26 22:23:46 raspberry2024 volumio[844]: info: explodeTIDALUri took 8147 milliseconds Apr 26 22:23:48 raspberry2024 volumio[844]: info: explodeTIDALUri took 10202 milliseconds Apr 26 22:23:53 raspberry2024 ntpd[660]: receive: Unexpected origin timestamp 0xebb76af8.ec8f4ca4 does not match aorg 0000000000.00000000 from server@81.168.83.93 xmt 0xebb76af9.0f581be7 Apr 26 22:23:55 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 26 22:23:55 raspberry2024 volumio[844]: info: CURURI: music-library Apr 26 22:23:55 raspberry2024 volumio[844]: error: Failed LSINFO: null Apr 26 22:23:56 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:24:07 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 26 22:24:07 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:24:09 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 26 22:24:09 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:24:10 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 26 22:24:10 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:24:12 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 26 22:24:13 raspberry2024 volumio[844]: info: Preload queue cleared Apr 26 22:24:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 22:24:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 22:24:14 raspberry2024 volumio[844]: info: Discovery: Getting this device information Apr 26 22:24:14 raspberry2024 volumio[844]: info: CoreCommandRouter::volumioGetState Apr 26 22:24:14 raspberry2024 volumio[844]: info: CorePlayQueue::getTrack 0 Apr 26 22:24:14 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 22:24:17 raspberry2024 volumio[844]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 26 22:24:18 raspberry2024 volumio[844]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:24:18 raspberry2024 volumio[844]: TypeError: Cannot read property 'length' of undefined Apr 26 22:24:18 raspberry2024 volumio[844]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 26 22:24:18 raspberry2024 volumio[844]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 26 22:24:18 raspberry2024 volumio[844]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 26 22:24:18 raspberry2024 volumio[844]: at Parser.emit (events.js:315:20) Apr 26 22:24:18 raspberry2024 volumio[844]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 26 22:24:18 raspberry2024 volumio[844]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 26 22:24:18 raspberry2024 volumio[844]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 26 22:24:18 raspberry2024 volumio[844]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 26 22:24:18 raspberry2024 volumio[844]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 26 22:24:18 raspberry2024 volumio[844]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 26 22:24:18 raspberry2024 volumio[844]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 26 22:24:18 raspberry2024 volumio[844]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 26 22:24:18 raspberry2024 volumio[844]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 26 22:24:18 raspberry2024 volumio[844]: at IncomingMessage.emit (events.js:327:22) Apr 26 22:24:18 raspberry2024 volumio[844]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 26 22:24:18 raspberry2024 volumio[844]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 26 22:24:18 raspberry2024 volumio[844]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:24:18 raspberry2024 sudo[4979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 22:23 Apr 26 22:24:18 raspberry2024 sudo[4979]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"