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
74.01k stars 31.05k forks source link

Sonos init error in async_add_speakers. Debug log attached. #88486

Closed nelgmo closed 1 year ago

nelgmo commented 1 year ago

The problem

According to debug log the sonos home and speakers are found on init. But then something causes it to crash rendering it useless. No sonos devices added.

What version of Home Assistant Core has the issue?

core-2023.2.5

What was the last working version of Home Assistant Core?

No response

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/

Diagnostics information

The diagnostics was not available. Please see debug log for more info.

Example YAML snippet

No response

Anything in the logs that might be useful for us?

2023-02-20 13:16:47.403 DEBUG (MainThread) [soco.data_structures_entry] soco.data_structures_entry imported
2023-02-20 13:16:47.555 DEBUG (MainThread) [homeassistant.components.sonos] Reached async_setup_entry, config={}
2023-02-20 13:16:48.141 DEBUG (MainThread) [homeassistant.components.sonos] Adding discovery job
2023-02-20 13:16:48.147 DEBUG (MainThread) [homeassistant.components.sonos] New SSDP discovery uid=RINCON_347E5CFAD20A01400: SsdpServiceInfo(upnp={'deviceType': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'friendlyName': '192.168.0.117 - Sonos SYMFONISK', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S21', 'modelDescription': 'Sonos SYMFONISK', 'modelName': 'Sonos SYMFONISK', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S21', 'softwareVersion': '57.14-37030', 'swGen': '1', 'hardwareVersion': '1.20.3.3-2.0', 'serialNum': '34-7E-5C-FA-D2-0A:1', 'MACAddress': '34:7E:5C:FA:D2:0A', 'UDN': 'uuid:RINCON_347E5CFAD20A01400', 'iconList': {'icon': {'id': '0', 'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-S21.png'}}, 'minCompatibleVersion': '56.0-00000', 'legacyCompatibleVersion': '36.0-00000', 'apiVersion': '1.18.9', 'minApiVersion': '1.1.0', 'displayVersion': '11.7', 'extraVersion': None, 'roomName': 'Bathroom', 'displayName': 'SYMFONISK', 'zoneType': '23', 'feature1': '0x00000000', 'feature2': '0x00403332', 'feature3': '0x0001110e', 'seriesid': 'A100', 'variant': '0', 'internalSpeakerSize': '5', 'bassExtension': '75.000', 'satGainOffset': '6.000', 'memory': '512', 'flash': '256', 'ampOnTime': '10', 'retailMode': '0', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:AlarmClock:1', 'serviceId': 'urn:upnp-org:serviceId:AlarmClock', 'controlURL': '/AlarmClock/Control', 'eventSubURL': '/AlarmClock/Event', 'SCPDURL': '/xml/AlarmClock1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:MusicServices:1', 'serviceId': 'urn:upnp-org:serviceId:MusicServices', 'controlURL': '/MusicServices/Control', 'eventSubURL': '/MusicServices/Event', 'SCPDURL': '/xml/MusicServices1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:DeviceProperties:1', 'serviceId': 'urn:upnp-org:serviceId:DeviceProperties', 'controlURL': '/DeviceProperties/Control', 'eventSubURL': '/DeviceProperties/Event', 'SCPDURL': '/xml/DeviceProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:SystemProperties:1', 'serviceId': 'urn:upnp-org:serviceId:SystemProperties', 'controlURL': '/SystemProperties/Control', 'eventSubURL': '/SystemProperties/Event', 'SCPDURL': '/xml/SystemProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ZoneGroupTopology:1', 'serviceId': 'urn:upnp-org:serviceId:ZoneGroupTopology', 'controlURL': '/ZoneGroupTopology/Control', 'eventSubURL': '/ZoneGroupTopology/Event', 'SCPDURL': '/xml/ZoneGroupTopology1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupManagement:1', 'serviceId': 'urn:upnp-org:serviceId:GroupManagement', 'controlURL': '/GroupManagement/Control', 'eventSubURL': '/GroupManagement/Event', 'SCPDURL': '/xml/GroupManagement1.xml'}, {'serviceType': 'urn:schemas-tencent-com:service:QPlay:1', 'serviceId': 'urn:tencent-com:serviceId:QPlay', 'controlURL': '/QPlay/Control', 'eventSubURL': '/QPlay/Event', 'SCPDURL': '/xml/QPlay1.xml'}]}, 'deviceList': {'device': [{'deviceType': 'urn:schemas-upnp-org:device:MediaServer:1', 'friendlyName': '192.168.0.117 - Sonos SYMFONISK Media Server', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S21', 'modelDescription': 'Sonos SYMFONISK Media Server', 'modelName': 'Sonos SYMFONISK', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S21', 'UDN': 'uuid:RINCON_347E5CFAD20A01400_MS', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:ContentDirectory:1', 'serviceId': 'urn:upnp-org:serviceId:ContentDirectory', 'controlURL': '/MediaServer/ContentDirectory/Control', 'eventSubURL': '/MediaServer/ContentDirectory/Event', 'SCPDURL': '/xml/ContentDirectory1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaServer/ConnectionManager/Control', 'eventSubURL': '/MediaServer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}]}}, {'deviceType': 'urn:schemas-upnp-org:device:MediaRenderer:1', 'friendlyName': 'Bathroom - Sonos SYMFONISK Media Renderer', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S21', 'modelDescription': 'Sonos SYMFONISK Media Renderer', 'modelName': 'Sonos SYMFONISK', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S21', 'UDN': 'uuid:RINCON_347E5CFAD20A01400_MR', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:RenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:RenderingControl', 'controlURL': '/MediaRenderer/RenderingControl/Control', 'eventSubURL': '/MediaRenderer/RenderingControl/Event', 'SCPDURL': '/xml/RenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaRenderer/ConnectionManager/Control', 'eventSubURL': '/MediaRenderer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:AVTransport:1', 'serviceId': 'urn:upnp-org:serviceId:AVTransport', 'controlURL': '/MediaRenderer/AVTransport/Control', 'eventSubURL': '/MediaRenderer/AVTransport/Event', 'SCPDURL': '/xml/AVTransport1.xml'}, {'serviceType': 'urn:schemas-sonos-com:service:Queue:1', 'serviceId': 'urn:sonos-com:serviceId:Queue', 'controlURL': '/MediaRenderer/Queue/Control', 'eventSubURL': '/MediaRenderer/Queue/Event', 'SCPDURL': '/xml/Queue1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupRenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:GroupRenderingControl', 'controlURL': '/MediaRenderer/GroupRenderingControl/Control', 'eventSubURL': '/MediaRenderer/GroupRenderingControl/Event', 'SCPDURL': '/xml/GroupRenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:VirtualLineIn:1', 'serviceId': 'urn:upnp-org:serviceId:VirtualLineIn', 'controlURL': '/MediaRenderer/VirtualLineIn/Control', 'eventSubURL': '/MediaRenderer/VirtualLineIn/Event', 'SCPDURL': '/xml/VirtualLineIn1.xml'}]}, 'X_Rhapsody-Extension': {'deviceID': 'urn:rhapsody-real-com:device-id-1-0:sonos_1:RINCON_347E5CFAD20A01400', 'deviceCapabilities': {'interactionPattern': {'@type': 'real-rhapsody-upnp-1-0'}}}, 'X_QPlay_SoftwareCapability': 'QPlay:2', 'iconList': {'icon': {'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-S21.png'}}}]}}, ssdp_usn='uuid:RINCON_347E5CFAD20A01400::urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_st='urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_location='http://192.168.0.117:1400/xml/device_description.xml', ssdp_nt=None, ssdp_udn='uuid:RINCON_347E5CFAD20A01400', ssdp_ext='', ssdp_server='Linux UPnP/1.0 Sonos/57.14-37030 (ZPS21)', ssdp_headers={'CACHE-CONTROL': 'max-age = 1800', 'EXT': '', 'LOCATION': 'http://192.168.0.117:1400/xml/device_description.xml', 'SERVER': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS21)', 'ST': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'USN': 'uuid:RINCON_347E5CFAD20A01400::urn:schemas-upnp-org:device:ZonePlayer:1', 'X-RINCON-HOUSEHOLD': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj', 'X-RINCON-BOOTSEQ': '214', 'X-RINCON-WIFIMODE': '0', 'X-RINCON-VARIANT': '0', 'HOUSEHOLD.SMARTSPEAKER.AUDIO': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y', '_timestamp': datetime.datetime(2023, 2, 20, 13, 16, 37, 555954), '_host': '192.168.0.117', '_port': 34910, '_local_addr': ('0.0.0.0', 36641), '_remote_addr': ('192.168.0.117', 34910), '_udn': 'uuid:RINCON_347E5CFAD20A01400', '_location_original': 'http://192.168.0.117:1400/xml/device_description.xml', 'location': 'http://192.168.0.117:1400/xml/device_description.xml'}, x_homeassistant_matching_domains=set())
2023-02-20 13:16:48.148 DEBUG (MainThread) [homeassistant.components.sonos] New SSDP discovery uid=RINCON_000E583E808001400: SsdpServiceInfo(upnp={'deviceType': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'friendlyName': '192.168.0.114 - Sonos Connect:Amp', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'ZP120', 'modelDescription': 'Sonos Connect:Amp', 'modelName': 'Sonos Connect:Amp', 'modelURL': 'http://www.sonos.com/products/zoneplayers/ZP120', 'softwareVersion': '57.14-37030', 'swGen': '1', 'hardwareVersion': '1.16.3.1-2.0', 'serialNum': '00-0E-58-3E-80-80:F', 'MACAddress': '00:0E:58:3E:80:80', 'UDN': 'uuid:RINCON_000E583E808001400', 'iconList': {'icon': {'id': '0', 'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-ZP120.png'}}, 'minCompatibleVersion': '56.0-00000', 'legacyCompatibleVersion': '36.0-00000', 'apiVersion': '1.18.9', 'minApiVersion': '1.1.0', 'displayVersion': '11.7', 'extraVersion': 'OTP: 1.1.1(1-16-3-0.9)', 'roomName': 'Living Room', 'displayName': 'Connect:Amp', 'zoneType': '3', 'feature1': '0x05000001', 'feature2': '0x00006172', 'feature3': '0x00031071', 'seriesid': 'P100', 'variant': '0', 'internalSpeakerSize': '-1', 'bassExtension': '60.000', 'satGainOffset': '1.000', 'memory': '32', 'flash': '32', 'flashRepartitioned': '1', 'ampOnTime': '425', 'retailMode': '0', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:AlarmClock:1', 'serviceId': 'urn:upnp-org:serviceId:AlarmClock', 'controlURL': '/AlarmClock/Control', 'eventSubURL': '/AlarmClock/Event', 'SCPDURL': '/xml/AlarmClock1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:MusicServices:1', 'serviceId': 'urn:upnp-org:serviceId:MusicServices', 'controlURL': '/MusicServices/Control', 'eventSubURL': '/MusicServices/Event', 'SCPDURL': '/xml/MusicServices1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:AudioIn:1', 'serviceId': 'urn:upnp-org:serviceId:AudioIn', 'controlURL': '/AudioIn/Control', 'eventSubURL': '/AudioIn/Event', 'SCPDURL': '/xml/AudioIn1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:DeviceProperties:1', 'serviceId': 'urn:upnp-org:serviceId:DeviceProperties', 'controlURL': '/DeviceProperties/Control', 'eventSubURL': '/DeviceProperties/Event', 'SCPDURL': '/xml/DeviceProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:SystemProperties:1', 'serviceId': 'urn:upnp-org:serviceId:SystemProperties', 'controlURL': '/SystemProperties/Control', 'eventSubURL': '/SystemProperties/Event', 'SCPDURL': '/xml/SystemProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ZoneGroupTopology:1', 'serviceId': 'urn:upnp-org:serviceId:ZoneGroupTopology', 'controlURL': '/ZoneGroupTopology/Control', 'eventSubURL': '/ZoneGroupTopology/Event', 'SCPDURL': '/xml/ZoneGroupTopology1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupManagement:1', 'serviceId': 'urn:upnp-org:serviceId:GroupManagement', 'controlURL': '/GroupManagement/Control', 'eventSubURL': '/GroupManagement/Event', 'SCPDURL': '/xml/GroupManagement1.xml'}, {'serviceType': 'urn:schemas-tencent-com:service:QPlay:1', 'serviceId': 'urn:tencent-com:serviceId:QPlay', 'controlURL': '/QPlay/Control', 'eventSubURL': '/QPlay/Event', 'SCPDURL': '/xml/QPlay1.xml'}]}, 'deviceList': {'device': [{'deviceType': 'urn:schemas-upnp-org:device:MediaServer:1', 'friendlyName': '192.168.0.114 - Sonos Connect:Amp Media Server', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'ZP120', 'modelDescription': 'Sonos Connect:Amp Media Server', 'modelName': 'Sonos Connect:Amp', 'modelURL': 'http://www.sonos.com/products/zoneplayers/ZP120', 'UDN': 'uuid:RINCON_000E583E808001400_MS', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:ContentDirectory:1', 'serviceId': 'urn:upnp-org:serviceId:ContentDirectory', 'controlURL': '/MediaServer/ContentDirectory/Control', 'eventSubURL': '/MediaServer/ContentDirectory/Event', 'SCPDURL': '/xml/ContentDirectory1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaServer/ConnectionManager/Control', 'eventSubURL': '/MediaServer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}]}}, {'deviceType': 'urn:schemas-upnp-org:device:MediaRenderer:1', 'friendlyName': 'Living Room - Sonos Connect:Amp Media Renderer', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'ZP120', 'modelDescription': 'Sonos Connect:Amp Media Renderer', 'modelName': 'Sonos Connect:Amp', 'modelURL': 'http://www.sonos.com/products/zoneplayers/ZP120', 'UDN': 'uuid:RINCON_000E583E808001400_MR', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:RenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:RenderingControl', 'controlURL': '/MediaRenderer/RenderingControl/Control', 'eventSubURL': '/MediaRenderer/RenderingControl/Event', 'SCPDURL': '/xml/RenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaRenderer/ConnectionManager/Control', 'eventSubURL': '/MediaRenderer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:AVTransport:1', 'serviceId': 'urn:upnp-org:serviceId:AVTransport', 'controlURL': '/MediaRenderer/AVTransport/Control', 'eventSubURL': '/MediaRenderer/AVTransport/Event', 'SCPDURL': '/xml/AVTransport1.xml'}, {'serviceType': 'urn:schemas-sonos-com:service:Queue:1', 'serviceId': 'urn:sonos-com:serviceId:Queue', 'controlURL': '/MediaRenderer/Queue/Control', 'eventSubURL': '/MediaRenderer/Queue/Event', 'SCPDURL': '/xml/Queue1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupRenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:GroupRenderingControl', 'controlURL': '/MediaRenderer/GroupRenderingControl/Control', 'eventSubURL': '/MediaRenderer/GroupRenderingControl/Event', 'SCPDURL': '/xml/GroupRenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:VirtualLineIn:1', 'serviceId': 'urn:upnp-org:serviceId:VirtualLineIn', 'controlURL': '/MediaRenderer/VirtualLineIn/Control', 'eventSubURL': '/MediaRenderer/VirtualLineIn/Event', 'SCPDURL': '/xml/VirtualLineIn1.xml'}]}, 'X_Rhapsody-Extension': {'deviceID': 'urn:rhapsody-real-com:device-id-1-0:sonos_1:RINCON_000E583E808001400', 'deviceCapabilities': {'interactionPattern': {'@type': 'real-rhapsody-upnp-1-0'}}}, 'X_QPlay_SoftwareCapability': 'QPlay:2', 'iconList': {'icon': {'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-ZP120.png'}}}]}}, ssdp_usn='uuid:RINCON_000E583E808001400::urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_st='urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_location='http://192.168.0.114:1400/xml/device_description.xml', ssdp_nt=None, ssdp_udn='uuid:RINCON_000E583E808001400', ssdp_ext='', ssdp_server='Linux UPnP/1.0 Sonos/57.14-37030 (ZP120)', ssdp_headers={'CACHE-CONTROL': 'max-age = 1800', 'EXT': '', 'LOCATION': 'http://192.168.0.114:1400/xml/device_description.xml', 'SERVER': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZP120)', 'ST': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'USN': 'uuid:RINCON_000E583E808001400::urn:schemas-upnp-org:device:ZonePlayer:1', 'X-RINCON-HOUSEHOLD': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj', 'X-RINCON-BOOTSEQ': '322', 'X-RINCON-WIFIMODE': '0', 'X-RINCON-VARIANT': '0', 'HOUSEHOLD.SMARTSPEAKER.AUDIO': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y', '_timestamp': datetime.datetime(2023, 2, 20, 13, 16, 37, 575518), '_host': '192.168.0.114', '_port': 35050, '_local_addr': ('0.0.0.0', 36641), '_remote_addr': ('192.168.0.114', 35050), '_udn': 'uuid:RINCON_000E583E808001400', '_location_original': 'http://192.168.0.114:1400/xml/device_description.xml', 'location': 'http://192.168.0.114:1400/xml/device_description.xml'}, x_homeassistant_matching_domains=set())
2023-02-20 13:16:48.148 DEBUG (MainThread) [homeassistant.components.sonos] New SSDP discovery uid=RINCON_000E5882183001400: SsdpServiceInfo(upnp={'deviceType': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'friendlyName': '192.168.0.120 - Sonos Play:5', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S5', 'modelDescription': 'Sonos Play:5', 'modelName': 'Sonos Play:5', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S5', 'softwareVersion': '57.14-37030', 'swGen': '1', 'hardwareVersion': '1.16.4.1-2.0', 'serialNum': '00-0E-58-82-18-30:1', 'MACAddress': '00:0E:58:82:18:30', 'UDN': 'uuid:RINCON_000E5882183001400', 'iconList': {'icon': {'id': '0', 'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-S5.png'}}, 'minCompatibleVersion': '56.0-00000', 'legacyCompatibleVersion': '36.0-00000', 'apiVersion': '1.18.9', 'minApiVersion': '1.1.0', 'displayVersion': '11.7', 'extraVersion': 'OTP: 1.1.1(1-16-4-zp5s-0.5)', 'roomName': 'Studio', 'displayName': 'Play:5', 'zoneType': '5', 'feature1': '0x02000002', 'feature2': '0x00006172', 'feature3': '0x0003102a', 'seriesid': 'P100', 'variant': '0', 'internalSpeakerSize': '3', 'bassExtension': '0.000', 'satGainOffset': '0.000', 'memory': '32', 'flash': '32', 'flashRepartitioned': '1', 'ampOnTime': '425', 'retailMode': '0', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:AlarmClock:1', 'serviceId': 'urn:upnp-org:serviceId:AlarmClock', 'controlURL': '/AlarmClock/Control', 'eventSubURL': '/AlarmClock/Event', 'SCPDURL': '/xml/AlarmClock1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:MusicServices:1', 'serviceId': 'urn:upnp-org:serviceId:MusicServices', 'controlURL': '/MusicServices/Control', 'eventSubURL': '/MusicServices/Event', 'SCPDURL': '/xml/MusicServices1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:AudioIn:1', 'serviceId': 'urn:upnp-org:serviceId:AudioIn', 'controlURL': '/AudioIn/Control', 'eventSubURL': '/AudioIn/Event', 'SCPDURL': '/xml/AudioIn1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:DeviceProperties:1', 'serviceId': 'urn:upnp-org:serviceId:DeviceProperties', 'controlURL': '/DeviceProperties/Control', 'eventSubURL': '/DeviceProperties/Event', 'SCPDURL': '/xml/DeviceProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:SystemProperties:1', 'serviceId': 'urn:upnp-org:serviceId:SystemProperties', 'controlURL': '/SystemProperties/Control', 'eventSubURL': '/SystemProperties/Event', 'SCPDURL': '/xml/SystemProperties1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ZoneGroupTopology:1', 'serviceId': 'urn:upnp-org:serviceId:ZoneGroupTopology', 'controlURL': '/ZoneGroupTopology/Control', 'eventSubURL': '/ZoneGroupTopology/Event', 'SCPDURL': '/xml/ZoneGroupTopology1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupManagement:1', 'serviceId': 'urn:upnp-org:serviceId:GroupManagement', 'controlURL': '/GroupManagement/Control', 'eventSubURL': '/GroupManagement/Event', 'SCPDURL': '/xml/GroupManagement1.xml'}, {'serviceType': 'urn:schemas-tencent-com:service:QPlay:1', 'serviceId': 'urn:tencent-com:serviceId:QPlay', 'controlURL': '/QPlay/Control', 'eventSubURL': '/QPlay/Event', 'SCPDURL': '/xml/QPlay1.xml'}]}, 'deviceList': {'device': [{'deviceType': 'urn:schemas-upnp-org:device:MediaServer:1', 'friendlyName': '192.168.0.120 - Sonos Play:5 Media Server', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S5', 'modelDescription': 'Sonos Play:5 Media Server', 'modelName': 'Sonos Play:5', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S5', 'UDN': 'uuid:RINCON_000E5882183001400_MS', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:ContentDirectory:1', 'serviceId': 'urn:upnp-org:serviceId:ContentDirectory', 'controlURL': '/MediaServer/ContentDirectory/Control', 'eventSubURL': '/MediaServer/ContentDirectory/Event', 'SCPDURL': '/xml/ContentDirectory1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaServer/ConnectionManager/Control', 'eventSubURL': '/MediaServer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}]}}, {'deviceType': 'urn:schemas-upnp-org:device:MediaRenderer:1', 'friendlyName': 'Studio - Sonos Play:5 Media Renderer', 'manufacturer': 'Sonos, Inc.', 'manufacturerURL': 'http://www.sonos.com', 'modelNumber': 'S5', 'modelDescription': 'Sonos Play:5 Media Renderer', 'modelName': 'Sonos Play:5', 'modelURL': 'http://www.sonos.com/products/zoneplayers/S5', 'UDN': 'uuid:RINCON_000E5882183001400_MR', 'serviceList': {'service': [{'serviceType': 'urn:schemas-upnp-org:service:RenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:RenderingControl', 'controlURL': '/MediaRenderer/RenderingControl/Control', 'eventSubURL': '/MediaRenderer/RenderingControl/Event', 'SCPDURL': '/xml/RenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:ConnectionManager:1', 'serviceId': 'urn:upnp-org:serviceId:ConnectionManager', 'controlURL': '/MediaRenderer/ConnectionManager/Control', 'eventSubURL': '/MediaRenderer/ConnectionManager/Event', 'SCPDURL': '/xml/ConnectionManager1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:AVTransport:1', 'serviceId': 'urn:upnp-org:serviceId:AVTransport', 'controlURL': '/MediaRenderer/AVTransport/Control', 'eventSubURL': '/MediaRenderer/AVTransport/Event', 'SCPDURL': '/xml/AVTransport1.xml'}, {'serviceType': 'urn:schemas-sonos-com:service:Queue:1', 'serviceId': 'urn:sonos-com:serviceId:Queue', 'controlURL': '/MediaRenderer/Queue/Control', 'eventSubURL': '/MediaRenderer/Queue/Event', 'SCPDURL': '/xml/Queue1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:GroupRenderingControl:1', 'serviceId': 'urn:upnp-org:serviceId:GroupRenderingControl', 'controlURL': '/MediaRenderer/GroupRenderingControl/Control', 'eventSubURL': '/MediaRenderer/GroupRenderingControl/Event', 'SCPDURL': '/xml/GroupRenderingControl1.xml'}, {'serviceType': 'urn:schemas-upnp-org:service:VirtualLineIn:1', 'serviceId': 'urn:upnp-org:serviceId:VirtualLineIn', 'controlURL': '/MediaRenderer/VirtualLineIn/Control', 'eventSubURL': '/MediaRenderer/VirtualLineIn/Event', 'SCPDURL': '/xml/VirtualLineIn1.xml'}]}, 'X_Rhapsody-Extension': {'deviceID': 'urn:rhapsody-real-com:device-id-1-0:sonos_1:RINCON_000E5882183001400', 'deviceCapabilities': {'interactionPattern': {'@type': 'real-rhapsody-upnp-1-0'}}}, 'X_QPlay_SoftwareCapability': 'QPlay:2', 'iconList': {'icon': {'mimetype': 'image/png', 'width': '48', 'height': '48', 'depth': '24', 'url': '/img/icon-S5.png'}}}]}}, ssdp_usn='uuid:RINCON_000E5882183001400::urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_st='urn:schemas-upnp-org:device:ZonePlayer:1', ssdp_location='http://192.168.0.120:1400/xml/device_description.xml', ssdp_nt=None, ssdp_udn='uuid:RINCON_000E5882183001400', ssdp_ext='', ssdp_server='Linux UPnP/1.0 Sonos/57.14-37030 (ZPS5)', ssdp_headers={'CACHE-CONTROL': 'max-age = 1800', 'EXT': '', 'LOCATION': 'http://192.168.0.120:1400/xml/device_description.xml', 'SERVER': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS5)', 'ST': 'urn:schemas-upnp-org:device:ZonePlayer:1', 'USN': 'uuid:RINCON_000E5882183001400::urn:schemas-upnp-org:device:ZonePlayer:1', 'X-RINCON-HOUSEHOLD': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj', 'X-RINCON-BOOTSEQ': '146755', 'X-RINCON-WIFIMODE': '0', 'X-RINCON-VARIANT': '0', 'HOUSEHOLD.SMARTSPEAKER.AUDIO': 'Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y', '_timestamp': datetime.datetime(2023, 2, 20, 13, 16, 37, 607898), '_host': '192.168.0.120', '_port': 36041, '_local_addr': ('0.0.0.0', 36641), '_remote_addr': ('192.168.0.120', 36041), '_udn': 'uuid:RINCON_000E5882183001400', '_location_original': 'http://192.168.0.120:1400/xml/device_description.xml', 'location': 'http://192.168.0.120:1400/xml/device_description.xml'}, x_homeassistant_matching_domains=set())
2023-02-20 13:16:48.173 DEBUG (MainThread) [soco.core] Created SoCo instance for ip: 192.168.0.117
2023-02-20 13:16:48.174 DEBUG (SyncWorker_4) [soco.services] Dispatching method GetHouseholdID
2023-02-20 13:16:48.175 DEBUG (SyncWorker_4) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:48.203 DEBUG (SyncWorker_4) [soco.services] Sending GetHouseholdID [] to 192.168.0.117
2023-02-20 13:16:48.248 DEBUG (SyncWorker_4) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:DeviceProperties:1#GetHouseholdID'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:GetHouseholdID xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"/>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:48.266 DEBUG (SyncWorker_4) [soco.services] Received {'CONTENT-LENGTH': '338', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS21)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetHouseholdIDResponse xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"><CurrentHouseholdID>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj</CurrentHouseholdID></u:GetHouseholdIDResponse></s:Body></s:Envelope>
2023-02-20 13:16:48.266 DEBUG (SyncWorker_4) [soco.services] Received status 200 from 192.168.0.117
2023-02-20 13:16:48.298 DEBUG (MainThread) [soco.events_asyncio] Event listener running on ('192.168.0.111', 1400)
2023-02-20 13:16:48.298 DEBUG (MainThread) [soco.events_asyncio] Event Listener started
2023-02-20 13:16:48.604 DEBUG (MainThread) [soco.events_base] Subscribed to http://192.168.0.117:1400/ZoneGroupTopology/Event, sid: uuid:RINCON_347E5CFAD20A01400_sub0000000036
2023-02-20 13:16:48.647 DEBUG (MainThread) [soco.events_asyncio] Event 0 received for ZoneGroupTopology service at 1676895408.647404
2023-02-20 13:16:48.647 DEBUG (MainThread) [soco.events_asyncio] Event content: <e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ZoneGroupState>&lt;ZoneGroupState&gt;&lt;ZoneGroups&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_347E5CFAD20A01400&quot; ID=&quot;RINCON_347E5CFAD20A01400:3332346938&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_347E5CFAD20A01400&quot; Location=&quot;http://192.168.0.117:1400/xml/device_description.xml&quot; ZoneName=&quot;Bathroom&quot; Icon=&quot;x-rincon-roomicon:bathroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;214&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;1&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E583E808001400&quot; ID=&quot;RINCON_000E583E808001400:67&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E583E808001400&quot; Location=&quot;http://192.168.0.114:1400/xml/device_description.xml&quot; ZoneName=&quot;Living Room&quot; Icon=&quot;x-rincon-roomicon:tvroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;322&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;0&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E5882183001400&quot; ID=&quot;RINCON_000E5882183001400:3360129257&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E5882183001400&quot; Location=&quot;http://192.168.0.120:1400/xml/device_description.xml&quot; ZoneName=&quot;Studio&quot; Icon=&quot;x-rincon-roomicon:living&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;146755&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;1&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;/ZoneGroups&gt;&lt;VanishedDevices&gt;&lt;/VanishedDevices&gt;&lt;/ZoneGroupState&gt;</ZoneGroupState></e:property><e:property><ThirdPartyMediaServersX>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</ThirdPartyMediaServersX></e:property><e:property><AvailableSoftwareUpdate>&lt;UpdateItem xmlns=&quot;urn:schemas-rinconnetworks-com:update-1-0&quot; Type=&quot;Software&quot; Version=&quot;57.14-37030&quot; UpdateURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/^57.14-37030&quot; DownloadSize=&quot;0&quot; ManifestURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/update.upm&quot; Swgen=&quot;1&quot; LatestSwgen=&quot;2&quot; ManifestRevision=&quot;1673367733&quot;/&gt;</AvailableSoftwareUpdate></e:property><e:property><AlarmRunSequence>RINCON_347E5CFAD20A01400:214:0</AlarmRunSequence></e:property><e:property><ZoneGroupName>Bathroom</ZoneGroupName></e:property><e:property><ZoneGroupID>RINCON_347E5CFAD20A01400:3332346938</ZoneGroupID></e:property><e:property><ZonePlayerUUIDsInGroup>RINCON_347E5CFAD20A01400</ZonePlayerUUIDsInGroup></e:property><e:property><MuseHouseholdId>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y</MuseHouseholdId></e:property><e:property><AreasUpdateID>47DEQpj8HBSa+/TImW+5JCeuQeRkm5NMpJWZG3hSuFU=</AreasUpdateID></e:property><e:property><SourceAreasUpdateID></SourceAreasUpdateID></e:property><e:property><NetsettingsUpdateID>105</NetsettingsUpdateID></e:property></e:propertyset>
2023-02-20 13:16:48.658 DEBUG (MainThread) [soco.zonegroupstate] Updating ZGS with event payload from 192.168.0.117 (1/1 processed)
2023-02-20 13:16:48.659 DEBUG (MainThread) [soco.core] Created SoCo instance for ip: 192.168.0.114
2023-02-20 13:16:48.660 DEBUG (MainThread) [soco.core] Created SoCo instance for ip: 192.168.0.120
2023-02-20 13:16:48.660 DEBUG (MainThread) [soco.zonegroupstate] Setting ZGS cache to 1200s
2023-02-20 13:16:48.662 DEBUG (MainThread) [homeassistant.components.sonos] Subscription to 192.168.0.117 succeeded
2023-02-20 13:16:48.662 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.117
2023-02-20 13:16:48.662 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.117
2023-02-20 13:16:48.753 DEBUG (SyncWorker_1) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Studio', 'player_icon': '/img/icon-S5.png', 'uid': 'RINCON_000E5882183001400', 'serial_number': '00-0E-58-82-18-30:1', 'software_version': '57.14-37030', 'hardware_version': '1.16.4.1-2.0', 'model_number': 'S5', 'model_name': 'Sonos Play:5', 'display_version': '11.7', 'mac_address': '00-0E-58-82-18-30'}
2023-02-20 13:16:48.754 DEBUG (SyncWorker_1) [soco.services] Dispatching method GetHouseholdID
2023-02-20 13:16:48.754 DEBUG (SyncWorker_1) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:48.853 DEBUG (SyncWorker_1) [soco.services] Sending GetHouseholdID [] to 192.168.0.120
2023-02-20 13:16:48.854 DEBUG (SyncWorker_1) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:DeviceProperties:1#GetHouseholdID'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:GetHouseholdID xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"/>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:48.895 DEBUG (SyncWorker_1) [soco.services] Received {'CONTENT-LENGTH': '338', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS5)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetHouseholdIDResponse xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"><CurrentHouseholdID>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj</CurrentHouseholdID></u:GetHouseholdIDResponse></s:Body></s:Envelope>
2023-02-20 13:16:48.896 DEBUG (SyncWorker_1) [soco.services] Received status 200 from 192.168.0.120
2023-02-20 13:16:48.897 DEBUG (SyncWorker_1) [soco.services] Dispatching method ListAlarms
2023-02-20 13:16:48.897 DEBUG (SyncWorker_1) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:48.979 DEBUG (SyncWorker_1) [soco.services] Sending ListAlarms [] to 192.168.0.120
2023-02-20 13:16:48.980 DEBUG (SyncWorker_1) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:AlarmClock:1#ListAlarms'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:ListAlarms xmlns:u="urn:schemas-upnp-org:service:AlarmClock:1"/>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:49.014 DEBUG (SyncWorker_1) [soco.services] Received {'CONTENT-LENGTH': '736', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS5)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:ListAlarmsResponse xmlns:u="urn:schemas-upnp-org:service:AlarmClock:1"><CurrentAlarmList>&lt;Alarms&gt;&lt;Alarm ID=&quot;1&quot; StartTime=&quot;12:45:00&quot; Duration=&quot;02:00:00&quot; Recurrence=&quot;ONCE&quot; Enabled=&quot;0&quot; RoomUUID=&quot;RINCON_000E583E808001400&quot; ProgramURI=&quot;x-rincon-buzzer:0&quot; ProgramMetaData=&quot;&quot; PlayMode=&quot;SHUFFLE&quot; Volume=&quot;30&quot; IncludeLinkedZones=&quot;0&quot;/&gt;&lt;/Alarms&gt;</CurrentAlarmList><CurrentAlarmListVersion>RINCON_000E583E808001400:3</CurrentAlarmListVersion></u:ListAlarmsResponse></s:Body></s:Envelope>
2023-02-20 13:16:49.014 DEBUG (SyncWorker_1) [soco.services] Received status 200 from 192.168.0.120
2023-02-20 13:16:49.015 DEBUG (SyncWorker_1) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.120
2023-02-20 13:16:49.018 DEBUG (SyncWorker_1) [homeassistant.components.sonos.alarms] Updating processed event 3 from <SoCo object at ip 192.168.0.120> (was None)
2023-02-20 13:16:49.019 DEBUG (SyncWorker_1) [soco.services] Dispatching method Browse
2023-02-20 13:16:49.020 DEBUG (SyncWorker_1) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:49.020 DEBUG (SyncWorker_1) [soco.services] Sending Browse [('ObjectID', 'FV:2'), ('BrowseFlag', 'BrowseDirectChildren'), ('Filter', '*'), ('StartingIndex', 0), ('RequestedCount', 100), ('SortCriteria', '')] to 192.168.0.120
2023-02-20 13:16:49.022 DEBUG (SyncWorker_1) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:ContentDirectory:1#Browse'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
      <ObjectID>FV:2</ObjectID>
      <BrowseFlag>BrowseDirectChildren</BrowseFlag>
      <Filter>*</Filter>
      <StartingIndex>0</StartingIndex>
      <RequestedCount>100</RequestedCount>
      <SortCriteria/>
    </u:Browse>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:49.141 DEBUG (SyncWorker_1) [soco.services] Received {'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'TRANSFER-ENCODING': 'chunked', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS5)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</Result><NumberReturned>27</NumberReturned><TotalMatches>27</TotalMatches><UpdateID>2</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
2023-02-20 13:16:49.142 DEBUG (SyncWorker_1) [soco.services] Received status 200 from 192.168.0.120
2023-02-20 13:16:49.156 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlFavorite 'b'[D (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.157 DEBUG (SyncWorker_1) [homeassistant.components.sonos.favorites] Processing favorites update_id 2 for <SoCo object at ip 192.168.0.120> (was: None)
2023-02-20 13:16:49.162 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlAudioBroadcast (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.163 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.164 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlAudioBroadcast (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.165 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.165 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.166 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.167 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.168 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlPlaylistContai (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:49.169 DEBUG (SyncWorker_1) [soco.data_structures_entry] Created data structures: [<DidlMusicTrack 'b' (CUT) from Didl string "<DIDL-Lite xmlns:dc=" (CUT)
2023-02-20 13:16:52.510 WARNING (MainThread) [homeassistant.components.media_player] Setup of media_player platform dlna_dmr is taking over 10 seconds.
2023-02-20 13:16:52.616 DEBUG (SyncWorker_4) [soco.services] Dispatching method GetHouseholdID
2023-02-20 13:16:52.616 DEBUG (SyncWorker_4) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:52.627 DEBUG (SyncWorker_4) [soco.services] Sending GetHouseholdID [] to 192.168.0.114
2023-02-20 13:16:52.627 DEBUG (SyncWorker_4) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:DeviceProperties:1#GetHouseholdID'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:GetHouseholdID xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"/>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:52.636 DEBUG (SyncWorker_4) [soco.services] Received {'CONTENT-LENGTH': '338', 'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZP120)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:GetHouseholdIDResponse xmlns:u="urn:schemas-upnp-org:service:DeviceProperties:1"><CurrentHouseholdID>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj</CurrentHouseholdID></u:GetHouseholdIDResponse></s:Body></s:Envelope>
2023-02-20 13:16:52.636 DEBUG (SyncWorker_4) [soco.services] Received status 200 from 192.168.0.114
2023-02-20 13:16:52.646 DEBUG (MainThread) [soco.events_base] Subscribed to http://192.168.0.114:1400/ZoneGroupTopology/Event, sid: uuid:RINCON_000E583E808001400_sub0000000037
2023-02-20 13:16:52.661 DEBUG (MainThread) [soco.events_asyncio] Event 0 received for ZoneGroupTopology service at 1676895412.6615143
2023-02-20 13:16:52.661 DEBUG (MainThread) [soco.events_asyncio] Event content: <e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ZoneGroupState>&lt;ZoneGroupState&gt;&lt;ZoneGroups&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E583E808001400&quot; ID=&quot;RINCON_000E583E808001400:67&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E583E808001400&quot; Location=&quot;http://192.168.0.114:1400/xml/device_description.xml&quot; ZoneName=&quot;Living Room&quot; Icon=&quot;x-rincon-roomicon:tvroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;322&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;0&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_347E5CFAD20A01400&quot; ID=&quot;RINCON_347E5CFAD20A01400:3332346938&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_347E5CFAD20A01400&quot; Location=&quot;http://192.168.0.117:1400/xml/device_description.xml&quot; ZoneName=&quot;Bathroom&quot; Icon=&quot;x-rincon-roomicon:bathroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;214&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;1&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E5882183001400&quot; ID=&quot;RINCON_000E5882183001400:3360129257&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E5882183001400&quot; Location=&quot;http://192.168.0.120:1400/xml/device_description.xml&quot; ZoneName=&quot;Studio&quot; Icon=&quot;x-rincon-roomicon:living&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;146755&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;1&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;/ZoneGroups&gt;&lt;/ZoneGroupState&gt;</ZoneGroupState></e:property><e:property><ThirdPartyMediaServersX>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</ThirdPartyMediaServersX></e:property><e:property><AvailableSoftwareUpdate>&lt;UpdateItem xmlns=&quot;urn:schemas-rinconnetworks-com:update-1-0&quot; Type=&quot;Software&quot; Version=&quot;57.14-37030&quot; UpdateURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/^57.14-37030&quot; DownloadSize=&quot;0&quot; ManifestURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/update.upm&quot; Swgen=&quot;1&quot; LatestSwgen=&quot;2&quot; ManifestRevision=&quot;1673367733&quot;/&gt;</AvailableSoftwareUpdate></e:property><e:property><AlarmRunSequence>RINCON_000E583E808001400:322:0</AlarmRunSequence></e:property><e:property><ZoneGroupName>Living Room</ZoneGroupName></e:property><e:property><ZoneGroupID>RINCON_000E583E808001400:67</ZoneGroupID></e:property><e:property><ZonePlayerUUIDsInGroup>RINCON_000E583E808001400</ZonePlayerUUIDsInGroup></e:property><e:property><MuseHouseholdId>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y</MuseHouseholdId></e:property><e:property><AreasUpdateID>47DEQpj8HBSa+/TImW+5JCeuQeRkm5NMpJWZG3hSuFU=</AreasUpdateID></e:property><e:property><SourceAreasUpdateID></SourceAreasUpdateID></e:property><e:property><NetsettingsUpdateID>105</NetsettingsUpdateID></e:property></e:propertyset>
2023-02-20 13:16:52.665 DEBUG (MainThread) [soco.zonegroupstate] Updating ZGS with event payload from 192.168.0.114 (2/5 processed)
2023-02-20 13:16:52.666 DEBUG (MainThread) [soco.zonegroupstate] Setting ZGS cache to 1200s
2023-02-20 13:16:52.666 DEBUG (MainThread) [homeassistant.components.sonos] Subscription to 192.168.0.114 succeeded
2023-02-20 13:16:52.666 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.114
2023-02-20 13:16:52.667 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.114
2023-02-20 13:16:52.687 DEBUG (SyncWorker_7) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Bathroom', 'player_icon': '/img/icon-S21.png', 'uid': 'RINCON_347E5CFAD20A01400', 'serial_number': '34-7E-5C-FA-D2-0A:1', 'software_version': '57.14-37030', 'hardware_version': '1.20.3.3-2.0', 'model_number': 'S21', 'model_name': 'Sonos SYMFONISK', 'display_version': '11.7', 'mac_address': '34-7E-5C-FA-D2-0A'}
2023-02-20 13:16:52.688 DEBUG (SyncWorker_7) [soco.services] Dispatching method Browse
2023-02-20 13:16:52.688 DEBUG (SyncWorker_7) [soco.services] Request timeout set to 9.5
2023-02-20 13:16:52.688 DEBUG (SyncWorker_7) [soco.services] Sending Browse [('ObjectID', 'FV:2'), ('BrowseFlag', 'BrowseDirectChildren'), ('Filter', '*'), ('StartingIndex', 0), ('RequestedCount', 100), ('SortCriteria', '')] to 192.168.0.117
2023-02-20 13:16:52.689 DEBUG (SyncWorker_7) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:ContentDirectory:1#Browse'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
      <ObjectID>FV:2</ObjectID>
      <BrowseFlag>BrowseDirectChildren</BrowseFlag>
      <Filter>*</Filter>
      <StartingIndex>0</StartingIndex>
      <RequestedCount>100</RequestedCount>
      <SortCriteria/>
    </u:Browse>
  </s:Body>
</s:Envelope>

2023-02-20 13:16:52.738 DEBUG (SyncWorker_7) [soco.services] Received {'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'TRANSFER-ENCODING': 'chunked', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZPS21)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</Result><NumberReturned>27</NumberReturned><TotalMatches>27</TotalMatches><UpdateID>2</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
2023-02-20 13:16:52.738 DEBUG (SyncWorker_7) [soco.services] Received status 200 from 192.168.0.117
2023-02-20 13:16:52.741 DEBUG (SyncWorker_7) [homeassistant.components.sonos.favorites] Processing favorites update_id 2 for <SoCo object at ip 192.168.0.117> (was: None)
2023-02-20 13:16:58.113 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 307, in async_add_speakers
    await self.hass.async_add_executor_job(_add_speakers)
  File "/usr/local/lib/python3.10/concurrent/futures/thread.py", line 58, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 304, in _add_speakers
    self._add_speaker(soco, sub)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 328, in _add_speaker
    new_coordinator.setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 42, in setup
    super().setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/household_coordinator.py", line 34, in setup
    self.update_cache(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/helpers.py", line 67, in wrapper
    result = funct(self, *args, **kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 128, in update_cache
    if fav.reference.resources:
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 983, in reference
    ref = _FROM_DIDL_STRING_FUNCTION(getattr(self, "resource_meta_data"))[0]
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures_entry.py", line 35, in from_didl_string
    cls = didl_class_to_soco_class(item_class)
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 79, in didl_class_to_soco_class
    if separator in didl_class:
TypeError: argument of type 'NoneType' is not iterable
2023-02-20 13:16:58.122 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 307, in async_add_speakers
    await self.hass.async_add_executor_job(_add_speakers)
  File "/usr/local/lib/python3.10/concurrent/futures/thread.py", line 58, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 304, in _add_speakers
    self._add_speaker(soco, sub)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 328, in _add_speaker
    new_coordinator.setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 42, in setup
    super().setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/household_coordinator.py", line 34, in setup
    self.update_cache(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/helpers.py", line 67, in wrapper
    result = funct(self, *args, **kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 128, in update_cache
    if fav.reference.resources:
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 983, in reference
    ref = _FROM_DIDL_STRING_FUNCTION(getattr(self, "resource_meta_data"))[0]
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures_entry.py", line 35, in from_didl_string
    cls = didl_class_to_soco_class(item_class)
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 79, in didl_class_to_soco_class
    if separator in didl_class:
TypeError: argument of type 'NoneType' is not iterable
2023-02-20 13:17:04.260 DEBUG (MainThread) [soco.events_base] Subscribed to http://192.168.0.114:1400/ZoneGroupTopology/Event, sid: uuid:RINCON_000E583E808001400_sub0000000038
2023-02-20 13:17:04.268 DEBUG (MainThread) [soco.events_asyncio] Event 0 received for ZoneGroupTopology service at 1676895424.2686152
2023-02-20 13:17:04.268 DEBUG (MainThread) [soco.events_asyncio] Event content: <e:propertyset xmlns:e="urn:schemas-upnp-org:event-1-0"><e:property><ZoneGroupState>&lt;ZoneGroupState&gt;&lt;ZoneGroups&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E583E808001400&quot; ID=&quot;RINCON_000E583E808001400:67&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E583E808001400&quot; Location=&quot;http://192.168.0.114:1400/xml/device_description.xml&quot; ZoneName=&quot;Living Room&quot; Icon=&quot;x-rincon-roomicon:tvroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;322&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;0&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_347E5CFAD20A01400&quot; ID=&quot;RINCON_347E5CFAD20A01400:3332346938&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_347E5CFAD20A01400&quot; Location=&quot;http://192.168.0.117:1400/xml/device_description.xml&quot; ZoneName=&quot;Bathroom&quot; Icon=&quot;x-rincon-roomicon:bathroom&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;214&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;5&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;1&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;ZoneGroup Coordinator=&quot;RINCON_000E5882183001400&quot; ID=&quot;RINCON_000E5882183001400:3360129257&quot;&gt;&lt;ZoneGroupMember UUID=&quot;RINCON_000E5882183001400&quot; Location=&quot;http://192.168.0.120:1400/xml/device_description.xml&quot; ZoneName=&quot;Studio&quot; Icon=&quot;x-rincon-roomicon:living&quot; Configuration=&quot;1&quot; SoftwareVersion=&quot;57.14-37030&quot; SWGen=&quot;1&quot; MinCompatibleVersion=&quot;56.0-00000&quot; LegacyCompatibleVersion=&quot;36.0-00000&quot; BootSeq=&quot;146755&quot; TVConfigurationError=&quot;0&quot; HdmiCecAvailable=&quot;0&quot; WirelessMode=&quot;0&quot; WirelessLeafOnly=&quot;0&quot; HasConfiguredSSID=&quot;1&quot; ChannelFreq=&quot;2437&quot; BehindWifiExtender=&quot;1&quot; WifiEnabled=&quot;1&quot; Orientation=&quot;0&quot; RoomCalibrationState=&quot;4&quot; SecureRegState=&quot;3&quot; VoiceConfigState=&quot;0&quot; MicEnabled=&quot;0&quot; AirPlayEnabled=&quot;0&quot; IdleState=&quot;1&quot; MoreInfo=&quot;&quot;/&gt;&lt;/ZoneGroup&gt;&lt;/ZoneGroups&gt;&lt;/ZoneGroupState&gt;</ZoneGroupState></e:property><e:property><ThirdPartyMediaServersX>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</ThirdPartyMediaServersX></e:property><e:property><AvailableSoftwareUpdate>&lt;UpdateItem xmlns=&quot;urn:schemas-rinconnetworks-com:update-1-0&quot; Type=&quot;Software&quot; Version=&quot;57.14-37030&quot; UpdateURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/^57.14-37030&quot; DownloadSize=&quot;0&quot; ManifestURL=&quot;http://update-firmware.sonos.com/firmware/Prod/57.14-37030-v11.7-oxmdyztc-GA-1/update.upm&quot; Swgen=&quot;1&quot; LatestSwgen=&quot;2&quot; ManifestRevision=&quot;1673367733&quot;/&gt;</AvailableSoftwareUpdate></e:property><e:property><AlarmRunSequence>RINCON_000E583E808001400:322:0</AlarmRunSequence></e:property><e:property><ZoneGroupName>Living Room</ZoneGroupName></e:property><e:property><ZoneGroupID>RINCON_000E583E808001400:67</ZoneGroupID></e:property><e:property><ZonePlayerUUIDsInGroup>RINCON_000E583E808001400</ZonePlayerUUIDsInGroup></e:property><e:property><MuseHouseholdId>Sonos_6SPcFO8m3geL2Y7ROaFqtmBjkj.4wjEGDlw_W9lx0mqv__Y</MuseHouseholdId></e:property><e:property><AreasUpdateID>47DEQpj8HBSa+/TImW+5JCeuQeRkm5NMpJWZG3hSuFU=</AreasUpdateID></e:property><e:property><SourceAreasUpdateID></SourceAreasUpdateID></e:property><e:property><NetsettingsUpdateID>105</NetsettingsUpdateID></e:property></e:propertyset>
2023-02-20 13:17:04.273 DEBUG (MainThread) [soco.zonegroupstate] Duplicate ZGS received from 192.168.0.114 (event), ignoring
2023-02-20 13:17:04.273 DEBUG (MainThread) [soco.zonegroupstate] Setting ZGS cache to 1200s
2023-02-20 13:17:04.274 DEBUG (MainThread) [homeassistant.components.sonos] Subscription to 192.168.0.114 succeeded
2023-02-20 13:17:04.274 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.114
2023-02-20 13:17:04.274 DEBUG (MainThread) [soco.zonegroupstate] Cache still active (GetZoneGroupState) during poll for 192.168.0.114
2023-02-20 13:17:04.326 DEBUG (SyncWorker_5) [homeassistant.components.sonos] Adding new speaker: {'zone_name': 'Living Room', 'player_icon': '/img/icon-ZP120.png', 'uid': 'RINCON_000E583E808001400', 'serial_number': '00-0E-58-3E-80-80:F', 'software_version': '57.14-37030', 'hardware_version': '1.16.3.1-2.0', 'model_number': 'ZP120', 'model_name': 'Sonos Connect:Amp', 'display_version': '11.7', 'mac_address': '00-0E-58-3E-80-80'}
2023-02-20 13:17:04.326 DEBUG (SyncWorker_5) [soco.services] Dispatching method Browse
2023-02-20 13:17:04.327 DEBUG (SyncWorker_5) [soco.services] Request timeout set to 9.5
2023-02-20 13:17:04.352 DEBUG (SyncWorker_5) [soco.services] Sending Browse [('ObjectID', 'FV:2'), ('BrowseFlag', 'BrowseDirectChildren'), ('Filter', '*'), ('StartingIndex', 0), ('RequestedCount', 100), ('SortCriteria', '')] to 192.168.0.114
2023-02-20 13:17:04.359 DEBUG (SyncWorker_5) [soco.services] Sending {'Content-Type': 'text/xml; charset="utf-8"', 'SOAPACTION': 'urn:schemas-upnp-org:service:ContentDirectory:1#Browse'}, <?xml version="1.0" ?>
<s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
  <s:Body>
    <u:Browse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1">
      <ObjectID>FV:2</ObjectID>
      <BrowseFlag>BrowseDirectChildren</BrowseFlag>
      <Filter>*</Filter>
      <StartingIndex>0</StartingIndex>
      <RequestedCount>100</RequestedCount>
      <SortCriteria/>
    </u:Browse>
  </s:Body>
</s:Envelope>

2023-02-20 13:17:04.468 DEBUG (SyncWorker_5) [soco.services] Received {'CONTENT-TYPE': 'text/xml; charset="utf-8"', 'EXT': '', 'TRANSFER-ENCODING': 'chunked', 'Server': 'Linux UPnP/1.0 Sonos/57.14-37030 (ZP120)', 'Connection': 'close'}, <s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/" s:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/"><s:Body><u:BrowseResponse xmlns:u="urn:schemas-upnp-org:service:ContentDirectory:1"><Result>***HAD TO CUT THIS DUE TO CHAR LIMITATION***</Result><NumberReturned>27</NumberReturned><TotalMatches>27</TotalMatches><UpdateID>1</UpdateID></u:BrowseResponse></s:Body></s:Envelope>
2023-02-20 13:17:04.468 DEBUG (SyncWorker_5) [soco.services] Received status 200 from 192.168.0.114
2023-02-20 13:17:04.471 DEBUG (SyncWorker_5) [homeassistant.components.sonos.favorites] Processing favorites update_id 1 for <SoCo object at ip 192.168.0.114> (was: None)
2023-02-20 13:17:04.483 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 307, in async_add_speakers
    await self.hass.async_add_executor_job(_add_speakers)
  File "/usr/local/lib/python3.10/concurrent/futures/thread.py", line 58, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 304, in _add_speakers
    self._add_speaker(soco, sub)
  File "/usr/src/homeassistant/homeassistant/components/sonos/__init__.py", line 328, in _add_speaker
    new_coordinator.setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 42, in setup
    super().setup(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/household_coordinator.py", line 34, in setup
    self.update_cache(soco)
  File "/usr/src/homeassistant/homeassistant/components/sonos/helpers.py", line 67, in wrapper
    result = funct(self, *args, **kwargs)
  File "/usr/src/homeassistant/homeassistant/components/sonos/favorites.py", line 128, in update_cache
    if fav.reference.resources:
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 983, in reference
    ref = _FROM_DIDL_STRING_FUNCTION(getattr(self, "resource_meta_data"))[0]
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures_entry.py", line 35, in from_didl_string
    cls = didl_class_to_soco_class(item_class)
  File "/usr/local/lib/python3.10/site-packages/soco/data_structures.py", line 79, in didl_class_to_soco_class
    if separator in didl_class:
TypeError: argument of type 'NoneType' is not iterable
2023-02-20 13:17:05.253 WARNING (MainThread) [homeassistant.components.zha.core.channels.base] [0x48D5:1:0x0b04]: async_initialize: all attempts have failed: [DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>'), DeliveryError('Failed to deliver message: <EmberStatus.DELIVERY_FAILED: 102>')]

Additional information

No response

home-assistant[bot] commented 1 year 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!

Code owner commands Code owners of `sonos` can trigger bot actions by commenting: - `@home-assistant close` Closes the issue. - `@home-assistant rename` Renames the issue. - `@home-assistant reopen` Reopen the issue. - `@home-assistant unassign sonos` Removes the current integration label and assignees on the issue, add the integration domain after the command.

(message by CodeOwnersMention)


sonos documentation sonos source (message by IssueLinks)

nelgmo commented 1 year ago

Full debug log attached: sonos.log

jjlawren commented 1 year ago

The error implies that one of your Sonos favorites isn't being parsed properly by the underlying library. It wasn't immediately obvious why from a quick look at the debug and manually parsing the favorites dump.

nelgmo commented 1 year ago

So then maybe the actual root cause is my bad taste in music 😆

On Mon, Feb 27, 2023, 06:34 jjlawren @.***> wrote:

The error implies that one of your Sonos favorites isn't being parsed properly by the underlying library. It wasn't immediately obvious why from a quick look at the debug and manually parsing the favorites dump.

— Reply to this email directly, view it on GitHub https://github.com/home-assistant/core/issues/88486#issuecomment-1445737775, or unsubscribe https://github.com/notifications/unsubscribe-auth/AAGCKDMNF4VDYOBNPLJODN3WZQ4IHANCNFSM6AAAAAAVB2N3PA . You are receiving this because you authored the thread.Message ID: @.***>

jjlawren commented 1 year ago

Ha, I wish it was that simple. Perhaps try update your Sonos favorites in the native app in some way by adding or removing a couple entries and then try to run HA again.

nelgmo commented 1 year ago

I completely erased everything in my Sonos Favorites and now it is working as expected. Will let you know if I add something that triggers the error again.

jjlawren commented 1 year ago

Nuclear option also works. Just curious, had you updated any of your favorites recently?

nelgmo commented 1 year ago

No I think it was quite a while ago. Also I have had some favorites in there for 15+ years,

issue-triage-workflows[bot] commented 1 year ago

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.