Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Playing on sonos device error 800 #52

Open
4 tasks done
Glohoui opened this issue Oct 18, 2024 · 5 comments
Open
4 tasks done

Playing on sonos device error 800 #52

Glohoui opened this issue Oct 18, 2024 · 5 comments

Comments

@Glohoui
Copy link

Glohoui commented Oct 18, 2024 •
edited
Loading

System Health details

System Information

version core-2024.10.2
installation_type Home Assistant Supervised
dev false
hassio true
docker true
user root
virtualenv false
python_version 3.12.4
os_name Linux
os_version 4.4.302+
arch x86_64
timezone Europe/Paris
config_dir /config
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 9
Home Assistant Cloud
logged_in false
can_reach_cert_server ok
can_reach_cloud_auth ok
can_reach_cloud ok
Home 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 storage
Recorder
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.3
Spotify
api_endpoint_reachable ok
SpotifyPlus
integration_version v1.0.60
clients_configured 1: John Doe (premium)
api_endpoint_reachable ok

Checklist

  • I have enabled SmartInspect debug logging for my installation.
  • I have filled out the issue template to the best of my ability.
  • This issue only contains 1 issue (if you have multiple issues, open one issue for each issue).
  • This issue is not a duplicate issue of any previous issues..

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.

  • Spotify plays perfectly (playlists/artists, etc...) from your integration when I choose my computer with Spotify desktop (in the logs)
  • Spotifyplus can pause/play on sonos when something is playing
  • I have successfully generated the OAuth2 Token file for Sonos via your Python script, renamed it to spotifyplus_tokens.json (not mentioned clearly on the wiki so I copied multiple times the same file with original name and auto-generated name) in the below folders (not clear in the wiki and it can't hurt ^^ ):
    - config/storage
    - homeassistant\homeassistant\custom_components\spotifyplus (Core)
    - homeassistant\homeassistant.storage (Core)
    - homeassistant\config (Supervisor)
    - homeassistant\config.storage (Supervisor)
  • Step 4b - Select Application Credential -> never appeared to me but I don't think this is the issue as spotify works perfectly
  • Step "Integration Options" -> the cannonical name in the id returned by the Spotify developers API was my spotify username and not an id as you listed. Therefore it is the same as the username in the Options (see attached jpg)
    2024-10-18 14 34 05

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

  1. Click on the on/off button in the integration
  2. select my sonos device
  3. Choose a title
    ...
    2024-10-18 14 23 17

Debug logs

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

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

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
@thlucas1
Copy link
Owner

@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):

image

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.

@Glohoui
Copy link
Author

Glohoui commented Oct 24, 2024

Hi and thanks for the quick answer.

The documentation is amazing and I believe a few things can be overlooked as it's so complete ^^

I have fully removed the sonos app and updated to the latest version of sonosplus app (I saw that you made a new release. )

Playing on a device that was not active seems now to be working.

However, choosing from a playlist/artist is still bringing up the issue Failed to perform the action media_player/play_media. UPnP Error 800 received: from 192.168.1.XX

Here is the image of the credentials . Because the only credentials that I have is for spotifyplus and I assigned it before installing the integration, I don't get to choose?
2024-10-24 16 57 42

Thanks :)

@thlucas1
Copy link
Owner

Was the playlist you are trying to play the Spotify DJ maybe? There are known issues with trying to play that specific playlist with the Spotify Web API (see SpotifyPlus Sonos Limitations wiki for more information). I get a UPnP Error 804 received: from 192.168.1.xx on my device as well when trying to play that specific playlist; there may be other "special" playlists out there as well, but that's the only one I have encountered thus far.

It also sounds like your Sonos device is playing content from its local queue instead of Spotify Connect, which can happen if playback cannot be transferred from a Spotify app (desktop / mobile) to SpotifyPlus. The SpotifyPlus integration transfers play to the Sonos device by issuing a play command via the Sonos SoCo API. If a Spotify Connect connection could not be established, then a local queue on the Sonos device is built from the Spotify Web API users queue, and a SoCo play command is issued to play the local queue. Note that Sonos playback control is transferred from the SPOTIFY_CONNECT music source to the Sonos device local queue when this happens; the Spotify Connect source is paused after control is transferred to the Sonos local queue. The only way for Spotify Connect to regain control on the Sonos Device is via a Spotify Desktop / Mobile application.

Not sure if that fully explains what is happening in this case, but without the specific Sonos gear that you have it's hard to tell.

@thlucas1
Copy link
Owner

thlucas1 commented Mar 9, 2025 •
edited
Loading

@Glohoui
FYI - just released a new version of the SpotifyPlus integration

The big takeaway with this release the the ability to set the Spotify Web Player Credentials in UI configuration options, instead of in the Token Cache File.

Setting these credentials should also fix the UPNP 800 errors you are seeing.

Be sure to check out the SpotifyPlus Card Dashboard as well.

Hope it helps!

@Glohoui
Copy link
Author

Glohoui commented Mar 10, 2025

Hi lucas,

sorry for the long answer, I was busy with other things.

I have managed to updated to the latest version (Before I was not using HACS but "regular" integration and I was stuck at version V.0.77.

I also have installed the spotifyPlusCard, what an amazing job :)

I managed to put my web player congiguration and it works. The big drawdown is that once my session is out, I have to get technical again. I chose your application because it could be a long term solution where the session was kept on by the spotify developper website.
I will try it on the long term without closing the session and see what happens.

Thanks a lot for the update, you do an amazing work :)

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@Glohoui @thlucas1