search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
73.69k stars 30.82k forks source link

Sonos Speech Enhancement state does not track actual state #62787

Closed crlogic closed 2 years ago

crlogic commented 2 years ago

The problem

On HA 2021.12.5 using the stock Sonos integration with disabled entities (bass/treble/etc), the Speech Enhancement switch does not accurately reflect state in Configuration --> Integrations --> Sonos --> Device (Eg. Beam..)

In the aforementioned GUI location, the Speech Enhancement button always reverts to on or off. This has flip/flopped depending on HA restarts. I suspect that initial HA core boot state influences whether it always reverts to display as on or always reverts to display as off.

I have an automation that turns Speech Enhancement on or off depending on trigger/conditions and that works as advertised. So this anomaly is related to displaying the state and not actual functionality.

Developer Tools --> States --> Entity also shows the incorrect state. Even if an automation has actually set the state as desired.

What version of Home Assistant Core has the issue?

2021.12.5

What was the last working version of Home Assistant Core?

N/A

What type of installation are you running?

Home Assistant OS

Integration causing the issue

Sonos

Link to integration documentation on our website

https://www.home-assistant.io/integrations/sonos/

Example YAML snippet

N/A - just click the switch in the GUI

Anything in the logs that might be useful for us?

[core-ssh config]$ cat home-assistant.log | grep sonos
2021-12-25 21:19:34 INFO (MainThread) [homeassistant.bootstrap] Setting up stage 2: {'zha', 'persistent_notification', 'input_number', 'scene', 'network', 'climate', 'light', 'openweathermap', 'unifi', 'group', 'script', 'broadlink', 'scheduler', 'dhcp', 'mobile_app', 'system_health', 'input_text', 'input_select', 'input_datetime', 'blueprint', 'default_config', 'history', 'sensor', 'usb', 'wyzeapi', 'hacs', 'input_boolean', 'shelly', 'media_source', 'tag', 'counter', 'tts', 'my', 'timer', 'notify', 'mqtt', 'zone', 'esphome', 'sun', 'binary_sensor', 'ssdp', 'automation', 'template', 'sonos', 'map', 'zeroconf', 'logbook', 'trace', 'emporia_vue', 'updater', 'tasmota', 'switch', 'energy'}
2021-12-25 21:19:36 INFO (MainThread) [homeassistant.setup] Setting up sonos
2021-12-25 21:19:36 INFO (MainThread) [homeassistant.setup] Setup of domain sonos took 0.0 seconds
2021-12-25 21:19:37 DEBUG (MainThread) [homeassistant.components.sonos] Reached async_setup_entry, config=OrderedDict([('advertise_addr', '192.168.0.96'), ('hosts', ['192.168.6.24', '192.168.6.27', '192.168.6.21', '192.168.6.22', '192.168.6.23', '192.168.6.28'])])
2021-12-25 21:19:37 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.sonos
2021-12-25 21:19:37 INFO (MainThread) [homeassistant.components.switch] Setting up switch.sonos
2021-12-25 21:19:37 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.sonos
2021-12-25 21:19:37 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.sonos
2021-12-25 21:19:37 INFO (MainThread) [homeassistant.components.number] Setting up number.sonos
2021-12-25 21:19:37 DEBUG (MainThread) [homeassistant.components.sonos] Adding discovery job
2021-12-25 21:19:37 DEBUG (SyncWorker_4) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Kitchen Sonos', 'player_icon': '/img/icon-S1.png', 'uid': 'RINCON_B8E93787A4DE01400', 'serial_number': 'B8-E9-37-87-A4-DE:F', 'software_version': '66.4-23300', 'hardware_version': '1.8.3.7-1.0', 'model_number': 'S1', 'model_name': 'Sonos Play:1', 'display_version': '13.4.1', 'mac_address': 'B8-E9-37-87-A4-DE'}
2021-12-25 21:19:37 DEBUG (SyncWorker_4) [homeassistant.components.sonos.alarms] Updating processed event 1054 from <SoCo object at ip 192.168.6.24> (was None)
2021-12-25 21:19:38 DEBUG (SyncWorker_4) [homeassistant.components.sonos.favorites] Processing favorites update_id 2 for <SoCo object at ip 192.168.6.24> (was: None)
2021-12-25 21:19:38 DEBUG (SyncWorker_4) [homeassistant.components.sonos.favorites] Cached 11 favorites for household Sonos_i3T3DdH7vhsbf6toDCtueEAl4A using <SoCo object at ip 192.168.6.24>
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating bass number control on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating treble number control on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.media_player] Creating media_player on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Creating subscriptions for Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Touch Controls switch on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Crossfade switch on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Status Light switch on Kitchen Sonos
2021-12-25 21:19:40 DEBUG (SyncWorker_4) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Bathroom Sonos', 'player_icon': '/img/icon-S1.png', 'uid': 'RINCON_000E58C661EE01400', 'serial_number': '00-0E-58-C6-61-EE:9', 'software_version': '66.4-23300', 'hardware_version': '1.8.3.7-1.0', 'model_number': 'S1', 'model_name': 'Sonos Play:1', 'display_version': '13.4.1', 'mac_address': '00-0E-58-C6-61-EE'}
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosSpeaker.update_volume
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Received event, cancelling poll timer for Kitchen Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from ContentDirectory subscription
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating bass number control on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating treble number control on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.media_player] Creating media_player on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Creating subscriptions for Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from AVTransport subscription
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from AlarmClock subscription
2021-12-25 21:19:40 DEBUG (SyncWorker_4) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'TV Sonos', 'player_icon': '/img/icon-S14.png', 'uid': 'RINCON_949F3ED381BB01400', 'serial_number': '94-9F-3E-D3-81-BB:B', 'software_version': '66.4-23300', 'hardware_version': '1.23.1.10-1.2', 'model_number': 'S14', 'model_name': 'Sonos Beam', 'display_version': '13.4.1', 'mac_address': '94-9F-3E-D3-81-BB'}
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosSpeaker.update_media
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Touch Controls switch on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Crossfade switch on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Status Light switch on Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from ZoneGroupTopology subscription
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from DeviceProperties subscription
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from SonosSpeaker.update_volume
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating bass number control on TV Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating treble number control on TV Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.sensor] Creating audio input format sensor on TV Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.media_player] Creating media_player on TV Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Creating subscriptions for TV Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Received event, cancelling poll timer for Bathroom Sonos
2021-12-25 21:19:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from ContentDirectory subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from AVTransport subscription
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Touch Controls switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Crossfade switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Night Sound switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Speech Enhancement switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Subwoofer Enabled switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Surround Enabled switch on TV Sonos
2021-12-25 21:19:50 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Status Light switch on TV Sonos
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from SonosSpeaker.update_media
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from AlarmClock subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_volume
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Received event, cancelling poll timer for TV Sonos
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ContentDirectory subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AVTransport subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_media
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AlarmClock subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from DeviceProperties subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ZoneGroupTopology subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Regrouped TV Sonos: ['media_player.tv_sonos', 'media_player.bathroom_sonos', 'media_player.kitchen_sonos']
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from DeviceProperties subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:19:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from RenderingControl subscription
2021-12-25 21:19:44 WARNING (SyncWorker_4) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef4da401f0>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:19:44 DEBUG (SyncWorker_4) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Bedroom Sonos', 'player_icon': '/img/icon-S1.png', 'uid': 'RINCON_000E58C29D2801400', 'serial_number': '00-0E-58-C2-9D-28:3', 'software_version': '66.4-23300', 'hardware_version': '1.8.3.7-1.0', 'model_number': 'S1', 'model_name': 'Sonos Play:1', 'display_version': '13.4.1', 'mac_address': '00-0E-58-C2-9D-28'}
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating bass number control on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.number] Creating treble number control on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating alarm 94 on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.media_player] Creating media_player on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Creating subscriptions for Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Touch Controls switch on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Crossfade switch on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.switch] Creating Status Light switch on Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from SonosSpeaker.update_volume
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Received event, cancelling poll timer for Bedroom Sonos
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ContentDirectory subscription
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.favorites] New favorites event 345 from <SoCo object at ip 192.168.6.28> (was None)
2021-12-25 21:19:44 INFO (MainThread) [homeassistant.components.automation.kitchen_sonos_mute_motion_end] Initialized trigger [MOTION END] Kitchen Sonos MUTE
2021-12-25 21:19:44 INFO (MainThread) [homeassistant.components.automation.sonos_boys_room_should_be_state] Initialized trigger [SCHEDULE] Boys Room Sonos
2021-12-25 21:19:44 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] Initialized trigger [STATE] Sonos Audio Input sets Speech Enhancement
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from AVTransport subscription
2021-12-25 21:19:44 DEBUG (SyncWorker_3) [homeassistant.components.sonos.favorites] Processing favorites update_id 2 for <SoCo object at ip 192.168.6.28> (was: None)
2021-12-25 21:19:44 DEBUG (SyncWorker_3) [homeassistant.components.sonos.favorites] Cached 11 favorites for household Sonos_i3T3DdH7vhsbf6toDCtueEAl4A using <SoCo object at ip 192.168.6.28>
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from AlarmClock subscription
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from SonosSpeaker.update_media
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Regrouped Bedroom Sonos: ['media_player.bedroom_sonos']
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from DeviceProperties subscription
2021-12-25 21:19:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from RenderingControl subscription
2021-12-25 21:20:12 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:20:12 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:20:24 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:20:24 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:20:28 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:20:28 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:20:47 WARNING (SyncWorker_2) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef47213070>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:20:54 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:20:54 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:21:50 WARNING (SyncWorker_9) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef4362b310>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:21:51 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AVTransport subscription
2021-12-25 21:21:51 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_media
2021-12-25 21:22:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Running automation actions
2021-12-25 21:22:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: default: Running automation actions
2021-12-25 21:22:13 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.mute_volume
2021-12-25 21:22:13 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:22:16 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from DeviceProperties subscription
2021-12-25 21:22:16 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:16 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:16 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:16 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from DeviceProperties subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from DeviceProperties subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:17 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:18 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:22:30 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.mute_volume
2021-12-25 21:22:30 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:22:53 WARNING (SyncWorker_7) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef436a03d0>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:22:59 INFO (MainThread) [homeassistant.components.automation.kitchen_sonos_mute_motion_end] [MOTION END] Kitchen Sonos MUTE: Running automation actions
2021-12-25 21:22:59 INFO (MainThread) [homeassistant.components.automation.kitchen_sonos_mute_motion_end] [MOTION END] Kitchen Sonos MUTE: Executing step call service
2021-12-25 21:22:59 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.mute_volume
2021-12-25 21:22:59 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:23:56 WARNING (SyncWorker_6) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef435e9520>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:24:18 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.mute_volume
2021-12-25 21:24:18 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:24:59 WARNING (SyncWorker_3) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef435d0730>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:25:48 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:48 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:48 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:48 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from DeviceProperties subscription
2021-12-25 21:25:48 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from DeviceProperties subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from DeviceProperties subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bathroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:49 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from ZoneGroupTopology subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:25:50 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:25:52 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AVTransport subscription
2021-12-25 21:25:52 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_media
2021-12-25 21:25:55 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:25:55 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:26:02 WARNING (SyncWorker_8) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef43e38850>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:26:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Running automation actions
2021-12-25 21:26:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: choice 1: Running automation actions
2021-12-25 21:26:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: choice 1: Executing step call service
2021-12-25 21:26:10 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:26:10 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:26:35 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:26:35 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:26:57 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:26:57 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:27:00 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:27:00 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:27:06 WARNING (SyncWorker_9) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef435e32b0>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:27:07 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:27:13 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:27:13 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:27:27 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AVTransport subscription
2021-12-25 21:27:27 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_media
2021-12-25 21:27:40 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Running automation actions
2021-12-25 21:27:40 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: default: Running automation actions
2021-12-25 21:27:47 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from AVTransport subscription
2021-12-25 21:27:47 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSpeaker.update_media
2021-12-25 21:28:09 WARNING (SyncWorker_9) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef4549a430>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:28:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Running automation actions
2021-12-25 21:28:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: choice 2: Running automation actions
2021-12-25 21:28:10 INFO (MainThread) [homeassistant.components.automation.state_sonos_audio_input_sets_speech_enhancement] [STATE] Sonos Audio Input sets Speech Enhancement: Choose at step 1: choice 2: Executing step call service
2021-12-25 21:28:10 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from SonosSwitchEntity.send_command
2021-12-25 21:28:10 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:29:12 WARNING (SyncWorker_6) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef4797cc10>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:30:15 WARNING (SyncWorker_5) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef435e0f40>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:30:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:40 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:41 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:30:43 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:43 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:43 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:30:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on TV Sonos from RenderingControl subscription
2021-12-25 21:30:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.set_volume_level
2021-12-25 21:30:44 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:31:03 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from DeviceProperties subscription
2021-12-25 21:31:05 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:31:12 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from DeviceProperties subscription
2021-12-25 21:31:13 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Bedroom Sonos from ZoneGroupTopology subscription
2021-12-25 21:31:18 WARNING (SyncWorker_5) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef43582580>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:31:54 INFO (MainThread) [homeassistant.components.automation.kitchen_sonos_mute_motion_end] [MOTION END] Kitchen Sonos MUTE: Running automation actions
2021-12-25 21:31:54 INFO (MainThread) [homeassistant.components.automation.kitchen_sonos_mute_motion_end] [MOTION END] Kitchen Sonos MUTE: Executing step call service
2021-12-25 21:31:54 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from SonosMediaPlayerEntity.mute_volume
2021-12-25 21:31:54 DEBUG (MainThread) [homeassistant.components.sonos.speaker] Activity on Kitchen Sonos from RenderingControl subscription
2021-12-25 21:32:21 WARNING (SyncWorker_7) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef4367ae50>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
2021-12-25 21:33:24 WARNING (SyncWorker_3) [homeassistant.components.sonos] Failed to connect to configured player '192.168.6.22': HTTPConnectionPool(host='192.168.6.22', port=1400): Max retries exceeded with url: /xml/ZoneGroupTopology1.xml (Caused by NewConnectionError('<urllib3.connection.HTTPConnection object at 0x7fef435c3f10>: Failed to establish a new connection: [Errno 113] Host is unreachable'))
[core-ssh config]$

Additional information

The Night Sound switch functions as advertised and reflects actual state instantly regardless if triggered by HA GUI button or Sonos app.

probot-home-assistant[bot] commented 2 years ago

sonos documentation sonos source (message by IssueLinks)

probot-home-assistant[bot] commented 2 years ago

Hey there @cgtobi, @jjlawren, mind taking a look at this issue as it has been labeled with an integration (sonos) you are listed as a code owner for? Thanks! (message by CodeOwnersMention)

jjlawren commented 2 years ago

I think this may be (unintentionally) fixed by https://github.com/home-assistant/core/pull/62691. The API was inconsistently named and it looks like I used the wrong name in the subscription callback handling method.

crlogic commented 2 years ago

Roger that. I'll report back when 12.6 drops.

Merry Christmas!

On Sat, Dec 25, 2021, 10:34 PM jjlawren @.***> wrote:

I think this may be (unintentionally) fixed by #62691 https://github.com/home-assistant/core/pull/62691. The API was inconsistently named and it looks like I used the wrong name in the subscription callback handling method.

— Reply to this email directly, view it on GitHub https://github.com/home-assistant/core/issues/62787#issuecomment-1001101860, or unsubscribe https://github.com/notifications/unsubscribe-auth/AASNI46XD7BSR3WECYATNZ3US2ELJANCNFSM5KYNHOBQ . Triage notifications on the go with GitHub Mobile for iOS https://apps.apple.com/app/apple-store/id1477376905?ct=notification-email&mt=8&pt=524675 or Android https://play.google.com/store/apps/details?id=com.github.android&referrer=utm_campaign%3Dnotification-email%26utm_medium%3Demail%26utm_source%3Dgithub.

You are receiving this because you authored the thread.Message ID: @.***>

jjlawren commented 2 years ago

At version 12.6, the speech enhancement is fixed but the night mode is broken

Can you please verify this and provide more info? No changes were made that should impact night mode.

crlogic commented 2 years ago

Can confirm Night Sound is now doing the same odd toggle behavior in 12.6 Sometimes it reports the correct state. Other times, it doesn't.

Additionally, after update, Speech Enhancement switch has now duplicated so I have two versions of this switch. The original is unavailable. The _2 versions appears to be working as expected with brief testing. The side effect is that I now need to find/replace previous automations using this switch.

[edit] spelling

03397 commented 2 years ago

At version 12.6, the speech enhancement is fixed but the night mode is broken

Can you please verify this and provide more info? No changes were made that should impact night mode.

Yes confirmed. I had the exact same behavior as crlogic described

jjlawren commented 2 years ago

Ah, the change that fixed the behavior also creates a duplicate switch. Sorry about that. You can delete the "old" one and rename the new entity as needed.

crlogic commented 2 years ago

You can delete the "old" one and rename the new entity as needed.

Roger that. Wasn't sure if that was intended and it would come back at some point.

Should we open a new issue for Night Sound?

[edit] issue created: https://github.com/home-assistant/core/issues/62902

jjlawren commented 2 years ago

While trying to rush fixes out I introduced a couple new problems. Here are upcoming fixes for those discussed in this issue:

  1. 63009

  2. 63010 attempts to avoid duplicates. If you already have duplicates (i.e., you've upgraded to 2021.12.6), you'll unfortunately need to manually clean those up by deleting the old (unavailable) entity and applying any name/icon/entity_id customizations you've made to the new working one.

jjlawren commented 2 years ago

Both of the above fixes are included in 2021.12.7 which should be available imminently.

If you've previously upgraded to 2021.12.6 and have not cleaned up the duplicate switches, you'll see a warning like this in your logs during startup:

Migration of Speech Enhancement switches on <SPEAKER_NAME> failed, manual cleanup required

Once you remove the old duplicate switch the warning will go away. Sorry for the inconvenience.