thlucas1
commented
3 days ago @Glohoui Hi Glo - thanks for reaching out, and your observations on the confusing wiki docs. I try to be clear and concise in my docs, but sometimes I fail miserably in those attempts. LOL. Thanks for bringing these issues to my attention.
I updated the documentation for the Spotify Connect Device LoginId value, making a note that the LoginId and the Username could be the same. I have noticed that in the past with other support questions, and just forgot to document it.
Regarding the location of the spotifyplus_tokens.json file, it needs to be placed in the Home Assistant /config/.storage location. Note that this folder could be hidden (for some operating system directory displays) due to the dot prefix. Use the How to find the HA configuration directory instructions for how to locate the base /config folder, then the .storage folder will be located beneath it. I will revise the docs to make that more clear.
Now, on to your problem ...
You mentioned "Step 4b - Select Application Credential -> never appeared to me but I don't think this is the issue as spotify works perfectly". It should have prompted you for the application credential to use when setting up the integration. SpotifyPlus will require an application credential to be defined (even if the HA Spotify integration does not). Can you verify that an application credential is assigned for SpotifyPlus? To do this, go to the HA Application Credentials dialog and see if you have any credentials assigned to SpotifyPlus. It should look something like this (I have 2 credentials defined, for my 2 Spotify accounts):
I think you have a credential assigned, otherwise you would be having issues selecting a playlist or controlling the player. But it does not hurt to verify that.
I see a UPnP Error 800 received: from 192.168.1.29 error, which indicates that the Sonos App (or something else) may have control of the device. You will want to make sure that the Sonos App is shut down while you are controlling the device from HA SpotifyPlus media player. I would also reboot the device, just to make sure it's not in a weird state. I have only been able to test Sonos functionality using my Ikea Symfonisk device, and I would see these same UPnP Error 800 messages whenever the device gets confused by multiple control sources (e.g. Sonos App, Spotify Mobile App, HA SpotifyPlus media player). I have found that not using the Sonos App at all works the best.
I would also try to start play on the Sonos Salon device from the official Spotify Desktop / Mobile App (not HA Spotify media player), just to make sure you don't get any UPnP 800 errors.
System Health details
System Information
Home Assistant Community Store
GitHub API | ok -- | -- GitHub Content | ok GitHub Web | ok HACS Data | ok GitHub API Calls Remaining | 4982 Installed Version | 2.0.1 Stage | running Available Repositories | 1443 Downloaded Repositories | 9Home Assistant Cloud
logged_in | false -- | -- can_reach_cert_server | ok can_reach_cloud_auth | ok can_reach_cloud | okHome Assistant Supervisor
host_os | null -- | -- update_channel | stable supervisor_version | supervisor-2024.10.2 agent_version | null docker_version | 20.10.23 healthy | failed to load: Unhealthy supported | failed to load: Unsupported host_connectivity | null supervisor_connectivity | true ntp_synchronized | null virtualization | supervisor_api | ok version_api | ok installed_addons | Tautulli (4.1.0), AirSonos (4.2.2), Spotify Connect (0.13.0), Mosquitto broker (6.4.1), deCONZ (7.0.0), Terminal & SSH (9.15.0), File editor (5.8.0), Zigbee2MQTT (1.40.2-1)Dashboards
dashboards | 5 -- | -- resources | 5 views | 10 mode | storageRecorder
oldest_recorder_run | October 12, 2024 at 6:23 PM -- | -- current_recorder_run | October 18, 2024 at 12:46 PM estimated_db_size | 549.94 MiB database_engine | sqlite database_version | 3.45.3Spotify
api_endpoint_reachable | ok -- | --SpotifyPlus
integration_version | v1.0.60 -- | -- clients_configured | 1: John Doe (premium) api_endpoint_reachable | okChecklist
Describe the issue
Hello,
I have followed step by step your amazing guide to install and configure the integration.
I have a homeassistant on a docker container running on a synology NAS and two player one first gen that work perfectly with the very bad Sonos application.
I think I did not forget anything, please don't hesitate to get back to me.
Thanks a lot for this integration, this is exactly what I am looking for :) Great job :)
Reproduction steps
Debug logs
Diagnostics dump
2024-10-18 13:54:16.889 WARNING (SyncWorker_0) [homeassistant.loader] We found a custom integration spotifyplus which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you experience issues with Home Assistant 2024-10-18 13:54:18.009 WARNING (MainThread) [homeassistant.helpers.translation] Failed to load integration for translation: Integration 'default' not found. 2024-10-18 13:54:22.851 ERROR (MainThread) [homeassistant.setup] Setup failed for 'default': Integration not found.
Traceback (most recent call last): File "/config/custom_components/spotifyplus/media_player.py", line 4552, in service_spotify_player_media_play_context self.data.spotifyClient.PlayerMediaPlayContext(contextUri, offsetUri, offsetPosition, positionMS, deviceId, delay) File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 11018, in PlayerMediaPlayContext self.MakeRequest('PUT', msg) File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 807, in MakeRequest msg.ResponseData = self._CheckResponseForErrors(msg, response) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 611, in _CheckResponseForErrors raise SpotifyWebApiError(errCode, errMessage, msg.MethodName, response.reason, _logsi) spotifywebapipython.spotifywebapierror.SpotifyWebApiError: SAM1001E - Spotify Web API returned an error status while processing the "PlayerMediaPlayContext" method. Status: 404 - Not Found Message: "Player command failed: No active device found"
During handling of the above exception, another exception occurred:
Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 245, in handle_call_service response = await hass.services.async_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2761, in async_call response_data = await coro ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2804, in _execute_service return await target(service_call) ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 996, in entity_service_call single_response = await _handle_entity_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 1068, in _handle_entity_call result = await task ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/components/media_player/init.py", line 878, in async_play_media await self.hass.async_add_executor_job( File "/usr/local/lib/python3.12/concurrent/futures/thread.py", line 58, in run result = self.fn(*self.args, self.kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, *args, *kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 844, in play_media self.service_spotify_player_media_play_context(media_id, deviceId=deviceId) File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, args, kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 4567, in service_spotify_player_media_play_context raise HomeAssistantError(ex.Message) homeassistant.exceptions.HomeAssistantError: Player command failed: No active device found 2024-10-18 14:02:39.768 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=1, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:02:39.768 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:02:39.768 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:02:39.768 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'None' 2024-10-18 14:02:39.814 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:02:39.814 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Source value is not set; using Spotify Web API player name ('None')
2024-10-18 14:02:48.775 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=21, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:02:49.775 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=20, lastKnownTimeRemainingSeconds=0, state=paused ..... 2024-10-18 14:03:06.789 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=3, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:07.790 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=2, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:08.791 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=1, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:08.791 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:08.791 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:08.791 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'None' 2024-10-18 14:03:08.844 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:08.845 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Source value is not set; using Spotify Web API player name ('None') 2024-10-18 14:03:09.791 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:10.792 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=28, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:11.792 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=27, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:12.793 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=26, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:13.795 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=25, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:14.795 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=24, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:15.796 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=23, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:16.797 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=22, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:17.797 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=21, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:17.935 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:17.935 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'None' 2024-10-18 14:03:17.974 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Transferring playback from source device name: '' (id=) 2024-10-18 14:03:18.798 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:03:19.775 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_play' 2024-10-18 14:03:19.798 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:03:19.894 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:19.895 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [140439745844496] Unexpected exception Traceback (most recent call last): File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 656, in media_play self.data.spotifyClient.PlayerMediaResume() File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 11377, in PlayerMediaResume self.MakeRequest('PUT', msg) File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 807, in MakeRequest msg.ResponseData = self._CheckResponseForErrors(msg, response) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/spotifywebapipython/spotifyclient.py", line 611, in _CheckResponseForErrors raise SpotifyWebApiError(errCode, errMessage, msg.MethodName, response.reason, _logsi) spotifywebapipython.spotifywebapierror.SpotifyWebApiError: SAM1001E - Spotify Web API returned an error status while processing the "PlayerMediaResume" method. Status: 404 - Not Found Message: "Player command failed: No active device found"
During handling of the above exception, another exception occurred:
Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 245, in handle_call_service response = await hass.services.async_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2761, in async_call response_data = await coro ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2804, in _execute_service return await target(service_call) ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 996, in entity_service_call single_response = await _handle_entity_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 1068, in _handle_entity_call result = await task ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/components/media_player/init.py", line 826, in async_media_play await self.hass.async_add_executor_job(self.media_play) File "/usr/local/lib/python3.12/concurrent/futures/thread.py", line 58, in run result = self.fn(*self.args, **self.kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 279, in wrapper raise HomeAssistantError(ex.Message) homeassistant.exceptions.HomeAssistantError: Player command failed: No active device found 2024-10-18 14:03:20.112 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Transferring playback to target device name: 'Salon (29254b4896dbcc7c5b632f4bddd5fd5104a045c9)' 2024-10-18 14:03:20.112 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Target device is a Sonos device; creating SoCo Controller instance for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:20.127 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device '192.168.1.29' ('Salon') music source: 'WEB_FILE' 2024-10-18 14:03:20.127 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Activating Spotify Connect music source on Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:20.157 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device '192.168.1.29' ('Salon') music source (after activation): 'WEB_FILE' 2024-10-18 14:03:20.169 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device '192.168.1.29' ('Salon') current_transport_state (before activation transfer): 'PAUSED_PLAYBACK' (Status=OK) 2024-10-18 14:03:20.170 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Spotify Web API PlayerState Item reference not set (nothing is playing) 2024-10-18 14:03:20.170 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): PLAY 2024-10-18 14:03:20.192 DEBUG (SyncWorker_6) [custom_components.spotifyplus] Delaying for 0.5 seconds to allow Sonos Soco API to process the change 2024-10-18 14:03:20.692 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:20.692 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'None' 2024-10-18 14:03:20.733 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Selected source was changed to: 'Salon' 2024-10-18 14:03:20.733 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a transfer playback command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:20.733 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Selected source was changed to: 'Salon' 2024-10-18 14:03:20.733 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:20.738 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=20, lastKnownTimeRemainingSeconds=0, state=playing 2024-10-18 14:03:20.738 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:20.738 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:20.739 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:20.802 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:20.802 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:20.836 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'playing' 2024-10-18 14:03:21.800 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=5, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=playing 2024-10-18 14:03:21.800 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:21.801 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:21.801 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:21.852 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:21.852 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:21.908 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'playing' 2024-10-18 14:03:22.802 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=4, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=playing 2024-10-18 14:03:22.802 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:22.802 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:22.802 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:22.842 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:22.842 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:22.873 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'playing' 2024-10-18 14:03:22.897 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_pause' 2024-10-18 14:03:22.898 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): PAUSE 2024-10-18 14:03:22.944 DEBUG (SyncWorker_5) [custom_components.spotifyplus] Delaying for 0.5 seconds to allow Sonos Soco API to process the change 2024-10-18 14:03:23.444 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:23.446 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:23.447 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:23.447 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:23.447 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:23.500 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:23.500 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:23.541 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:23.772 DEBUG (MainThread) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is browsing main media library index content id 'None' 2024-10-18 14:03:23.803 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=5, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:23.803 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:23.803 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:23.803 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:23.854 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:23.854 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:23.891 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:24.803 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=4, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:24.804 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:24.804 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:24.804 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:24.851 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:24.851 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:24.882 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:24.977 DEBUG (MainThread) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is browsing media node content id 'spotify_user_playlists' 2024-10-18 14:03:24.981 DEBUG (SyncWorker_9) [custom_components.spotifyplus.browse_media] 'SpotifyPlus John Doe': querying spotify for Playlist Favorites 2024-10-18 14:03:25.805 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=3, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:25.805 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:25.805 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:25.805 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:25.855 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:25.855 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:25.898 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:26.631 DEBUG (SyncWorker_6) [custom_components.spotifyplus] Playing via PlayerMediaPlayContext: contextUri='spotify:playlist:1h8McMFTTNvrffm6nquUDZ', deviceId='Salon' 2024-10-18 14:03:26.632 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): CLEAR_QUEUE 2024-10-18 14:03:26.649 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): ADD_SHARE_LINK_TO_QUEUE (uri=spotify:playlist:1h8McMFTTNvrffm6nquUDZ) 2024-10-18 14:03:26.805 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:03:26.909 ERROR (SyncWorker_6) [custom_components.spotifyplus] UPnP Error 800 received: from 192.168.1.29 Traceback (most recent call last): File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, *args, kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 4529, in service_spotify_player_media_play_context sharelink.add_share_link_to_queue(uri) File "/usr/local/lib/python3.12/site-packages/soco/plugins/sharelink.py", line 279, in add_share_link_to_queue raise fault File "/usr/local/lib/python3.12/site-packages/soco/plugins/sharelink.py", line 261, in add_share_link_to_queue response = self.soco.avTransport.AddURIToQueue( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 207, in _dispatcher return self.send_command(action, *args, *kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 515, in send_command self.handle_upnp_error(response.text) File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 571, in handle_upnp_error raise SoCoUPnPException( soco.exceptions.SoCoUPnPException: UPnP Error 800 received: from 192.168.1.29 2024-10-18 14:03:26.912 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:26.912 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:03:26.916 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [140439745844496] Unexpected exception Traceback (most recent call last): File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, args, kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 4529, in service_spotify_player_media_play_context sharelink.add_share_link_to_queue(uri) File "/usr/local/lib/python3.12/site-packages/soco/plugins/sharelink.py", line 279, in add_share_link_to_queue raise fault File "/usr/local/lib/python3.12/site-packages/soco/plugins/sharelink.py", line 261, in add_share_link_to_queue response = self.soco.avTransport.AddURIToQueue( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 207, in _dispatcher return self.send_command(action, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 515, in send_command self.handle_upnp_error(response.text) File "/usr/local/lib/python3.12/site-packages/soco/services.py", line 571, in handle_upnp_error raise SoCoUPnPException( soco.exceptions.SoCoUPnPException: UPnP Error 800 received: from 192.168.1.29
The above exception was the direct cause of the following exception:
Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 245, in handle_call_service response = await hass.services.async_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2761, in async_call response_data = await coro ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/core.py", line 2804, in _execute_service return await target(service_call) ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 996, in entity_service_call single_response = await _handle_entity_call( ^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 1068, in _handle_entity_call result = await task ^^^^^^^^^^ File "/usr/src/homeassistant/homeassistant/components/media_player/init.py", line 878, in async_play_media await self.hass.async_add_executor_job( File "/usr/local/lib/python3.12/concurrent/futures/thread.py", line 58, in run result = self.fn(*self.args, *self.kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 265, in wrapper result = func(self, args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/config/custom_components/spotifyplus/media_player.py", line 844, in play_media self.service_spotify_player_media_play_context(media_id, deviceId=deviceId) File "/config/custom_components/spotifyplus/media_player.py", line 282, in wrapper raise HomeAssistantError(str(ex)) from ex homeassistant.exceptions.HomeAssistantError: UPnP Error 800 received: from 192.168.1.29 2024-10-18 14:03:27.806 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=playing 2024-10-18 14:03:27.807 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:27.807 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:27.807 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:27.850 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:27.850 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:27.887 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:28.128 ERROR (SyncWorker_11) [homeassistant.components.influxdb] Cannot connect to InfluxDB due to 'HTTPConnectionPool(host='a0d7b954-influxdb', port=8086): Max retries exceeded with url: /write?db=homeassistant (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fba863e2180>: Failed to establish a new connection: [Errno -5] Name has no usable address'))'. Please check that the provided connection details (host, port, etc.) are correct and that your InfluxDB server is running and accessible. Retrying in 60 seconds. 2024-10-18 14:03:28.808 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=5, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:28.808 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:28.808 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:28.808 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:28.853 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:28.854 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:28.895 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:29.808 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=4, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:29.808 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:29.808 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:29.809 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:29.852 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:29.853 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:29.893 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:30.809 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=3, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:30.809 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:30.810 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:30.810 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:30.854 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:30.854 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:30.886 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:31.810 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=2, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:31.810 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:03:31.810 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:03:31.810 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:03:31.867 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:03:31.868 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:03:31.906 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:03:32.810 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=1, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:33.811 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=28, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:34.811 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=27, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:35.812 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=26, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:36.813 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=25, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:37.814 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=24, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:38.814 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=23, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:39.815 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=22, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:40.817 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=21, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:41.818 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=20, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:42.819 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=19, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:43.820 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=18, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:44.820 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=17, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:45.821 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=16, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:46.822 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=15, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:47.823 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=14, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:48.824 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=13, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:49.825 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=12, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:50.826 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=11, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:51.827 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=10, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:52.829 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=9, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:53.830 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=8, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:54.831 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=7, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:55.832 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=6, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:56.823 ERROR (MainThread) [hole] Can not load data from *hole: 192.168.1.123:3030 2024-10-18 14:03:56.832 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=5, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:57.833 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=4, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:58.834 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=3, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:03:59.835 DEBUG (SyncWorker_10) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=2, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:04:00.835 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=1, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:04:00.836 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:00.836 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:00.836 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:04:00.881 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:04:00.881 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:04:00.913 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:01.836 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:04:02.837 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=0, currentScanInterval=28, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:04:02.932 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:02.933 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:04:02.987 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:04:02.988 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:04:03.034 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Transferring playback from source device name: 'Salon' (id=29254b4896dbcc7c5b632f4bddd5fd5104a045c9) 2024-10-18 14:04:03.034 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device is active; issuing command to PAUSE Sonos player name 'Salon' 2024-10-18 14:04:03.047 DEBUG (SyncWorker_7) [custom_components.spotifyplus] Delaying for 0.5 seconds to allow Sonos Soco API to process the change 2024-10-18 14:04:03.838 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:04:04.252 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_play' 2024-10-18 14:04:04.253 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): PLAY 2024-10-18 14:04:04.288 DEBUG (SyncWorker_5) [custom_components.spotifyplus] Delaying for 0.5 seconds to allow Sonos Soco API to process the change 2024-10-18 14:04:04.788 DEBUG (SyncWorker_5) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:04.791 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=27, lastKnownTimeRemainingSeconds=0, state=playing 2024-10-18 14:04:04.791 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:04.791 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:04.792 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:04:04.838 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Sonos device detected; setting Sonos Control API reference for Sonos device '192.168.1.29' ('Salon') 2024-10-18 14:04:04.838 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Sonos device status via Sonos Control API for device: '192.168.1.29' ('Salon') 2024-10-18 14:04:04.888 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'playing' 2024-10-18 14:04:05.119 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_pause' 2024-10-18 14:04:05.120 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Issuing command to Sonos device '192.168.1.29' ('Salon'): PAUSE 2024-10-18 14:04:05.148 DEBUG (SyncWorker_8) [custom_components.spotifyplus] Delaying for 0.5 seconds to allow Sonos Soco API to process the change 2024-10-18 14:04:05.653 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:05.670 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Transferring playback to target device name: 'DESKTOP-G14 (be063b84c3b61e8a63bbf18434aea26cbd71a31a)' 2024-10-18 14:04:05.683 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=0, state=paused 2024-10-18 14:04:05.683 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:05.683 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:05.683 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'Salon' 2024-10-18 14:04:05.865 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:05.866 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Source value ('Salon') does not match Spotify Web API player name ('DESKTOP-G14'); using Spotify Web API player name 2024-10-18 14:04:05.866 DEBUG (SyncWorker_3) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=23332, DurationMS=227735, lastKnownTimeRemainingSeconds=204 2024-10-18 14:04:06.391 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:06.391 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:06.472 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Selected source was changed to: 'DESKTOP-G14' 2024-10-18 14:04:06.472 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a transfer playback command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:06.473 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Selected source was changed to: 'DESKTOP-G14' 2024-10-18 14:04:06.473 DEBUG (SyncWorker_7) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:06.476 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=204, state=paused 2024-10-18 14:04:06.476 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:06.476 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:06.476 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:06.574 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:06.574 DEBUG (SyncWorker_0) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=23332, DurationMS=227735, lastKnownTimeRemainingSeconds=204 2024-10-18 14:04:06.842 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=5, currentScanInterval=29, lastKnownTimeRemainingSeconds=204, state=paused 2024-10-18 14:04:06.843 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:06.843 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:06.843 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:06.963 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:06.963 DEBUG (SyncWorker_11) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=23332, DurationMS=227735, lastKnownTimeRemainingSeconds=204 2024-10-18 14:04:07.651 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_play' 2024-10-18 14:04:07.843 DEBUG (SyncWorker_6) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:04:08.525 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:08.527 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=204, state=playing 2024-10-18 14:04:08.528 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:08.528 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:08.528 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:08.623 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'playing' 2024-10-18 14:04:08.623 DEBUG (SyncWorker_9) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=24040, DurationMS=227735, lastKnownTimeRemainingSeconds=203 2024-10-18 14:04:08.639 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayer is executing service 'media_pause' 2024-10-18 14:04:08.843 DEBUG (SyncWorker_1) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Update - Integration is in a command event; bypassing update 2024-10-18 14:04:09.446 DEBUG (SyncWorker_2) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Processed a media player command - forcing a playerState scan window for the next 5 updates 2024-10-18 14:04:09.449 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=6, currentScanInterval=29, lastKnownTimeRemainingSeconds=203, state=paused 2024-10-18 14:04:09.449 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:09.449 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:09.449 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:09.539 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:09.540 DEBUG (SyncWorker_8) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=24106, DurationMS=227735, lastKnownTimeRemainingSeconds=203 2024-10-18 14:04:09.844 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Scan interval 30 check - commandScanInterval=5, currentScanInterval=29, lastKnownTimeRemainingSeconds=203, state=paused 2024-10-18 14:04:09.845 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': update method - getting Spotify Connect Player state 2024-10-18 14:04:09.845 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Getting Spotify Connect Player state 2024-10-18 14:04:09.845 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': Currently selected Source: 'DESKTOP-G14' 2024-10-18 14:04:09.924 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': MediaPlayerState set to 'paused' 2024-10-18 14:04:09.924 DEBUG (SyncWorker_4) [custom_components.spotifyplus] 'SpotifyPlus John Doe': playerState track ProgressMS=24106, DurationMS=227735, lastKnownTimeRemainingSeconds=203