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
71.34k stars 29.88k forks source link

Denon AVR for Marantz SR5006 not working in 2021.5 #50215

Closed j2deen closed 3 years ago

j2deen commented 3 years ago

The problem

It appears post upgrading to 2021.5 that the integration with DenonAVR for the Marantz SR5006 does not work. Have tried both autodiscovery and by IP address (Amp has a static IP) but it does not see the device. I have removed the integration and tried to re-add it and restarted Home Assistant. Any help appreciated. Can generate logs if needed.

What is version of Home Assistant Core has the issue?

2021.5

What was the last working version of Home Assistant Core?

2021.4

What type of installation are you running?

Home Assistant Container

Integration causing the issue

denonavr

Link to integration documentation on our website

https://www.home-assistant.io/integrations/denonavr/#configuration

Example YAML snippet

Tried adding from the Integration menu. I have confirmed the amp is up and running and the web interface is available.

Anything in the logs that might be useful for us?

No response

Additional information

No response

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

denonavr documentation denonavr source (message by IssueLinks)

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

Hey there @scarface-4711, @starkillerog, mind taking a look at this issue as its been labeled with an integration (denonavr) you are listed as a codeowner for? Thanks! (message by CodeOwnersMention)

ol-iver commented 3 years ago

@j2deen yes please, logs would be very helpful.

j2deen commented 3 years ago

Hi Oliver - here is the excerpts from the log from the log. If there is a specific set of logs you'd like let me know - I just turned everything on debug. Tried setting homeassistant.components.denonavr to debug in the logger config but didn't seem to work.

The amp is on 192.168.1.15, and I have indeed power cycled as per directions. The web interface is available and as mentioned has worked for many years previously. It is discovered by HomeAssistant on a restart - but setting it up either through discovery or by using the specific IP does not work. Is it possible (just inferring based on the exception) there was a different way Marantz Amps were being controlled rather than DLNA (which I think this is trying to do?)

Here is what the exceptions were:

2021-05-08 00:09:39 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15/goform/Deviceinfo.xml "HTTP/1.1 200 Data follows" 2021-05-08 00:09:39 DEBUG (MainThread) [denonavr.decorators] Exception Returned document contains HTML raised, clearing cache 2021-05-08 00:09:39 DEBUG (MainThread) [denonavr.foundation] Request error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/foundation.py", line 131, in async_identify_receiver xml = await self.api.async_get_xml( File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 90, in wrapper return await func(*args, *kwargs) File "/usr/local/lib/python3.8/site-packages/async_lru.py", line 237, in wrapped return (yield from asyncio.shield(fut, loop=_loop)) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 143, in async_get_xml self.check_xml_validity(request, xml_root) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 246, in check_xml_validity raise AvrInvalidResponseError( denonavr.exceptions.AvrInvalidResponseError: Returned document contains HTML

2021-05-08 00:09:54 DEBUG (MainThread) [denonavr.decorators] HTTP timeout exception on request <Request('GET', 'http://192.168.1.15:8080/goform/Deviceinfo.xml')> Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 61, in map_httpcore_exceptions yield File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 200, in aiter async for part in self._httpcore_stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/connection_pool.py", line 62, in aiter async for chunk in self.stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_bytestreams.py", line 91, in aiter async for chunk in self._aiterator: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 152, in _receive_response_data event = await self._receive_event(timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 169, in _receive_event data = await self.socket.read(self.READ_NUM_BYTES, timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_backends/asyncio.py", line 156, in read raise File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpcore/_exceptions.py", line 12, in map_exceptions raise to_exc(exc) from None httpcore.ReadTimeout

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(*args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 85, in async_get res = await client.get(endpoint, timeout=self.timeout) File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1662, in get return await self.request( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1426, in request response = await self.send( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1529, in send raise exc File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1520, in send await response.aread() File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in aread self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1578, in aiter_bytes async for raw_bytes in self.aiter_raw(): File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1632, in aiter_raw async for raw_stream_bytes in self.stream: File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 127, in aiter async for chunk in self._stream: File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 201, in aiter yield part File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 78, in map_httpcore_exceptions raise mapped_exc(message) from exc httpx.ReadTimeout 2021-05-08 00:09:54 DEBUG (MainThread) [denonavr.decorators] Exception TimeoutException: raised, clearing cache 2021-05-08 00:09:54 DEBUG (MainThread) [denonavr.foundation] Connection error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 61, in map_httpcore_exceptions yield File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 200, in aiter async for part in self._httpcore_stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/connection_pool.py", line 62, in aiter async for chunk in self.stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_bytestreams.py", line 91, in aiter async for chunk in self._aiterator: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 152, in _receive_response_data event = await self._receive_event(timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 169, in _receive_event data = await self.socket.read(self.READ_NUM_BYTES, timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_backends/asyncio.py", line 156, in read raise File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpcore/_exceptions.py", line 12, in map_exceptions raise to_exc(exc) from None httpcore.ReadTimeout

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(*args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 85, in async_get res = await client.get(endpoint, timeout=self.timeout) File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1662, in get return await self.request( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1426, in request response = await self.send( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1529, in send raise exc File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1520, in send await response.aread() File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in aread self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in self._content = b"".join([part async for part in self.aiter_bytes()])

2021-05-08 00:10:31 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15/goform/Deviceinfo.xml "HTTP/1.1 200 Data follows" 2021-05-08 00:10:31 DEBUG (MainThread) [denonavr.decorators] Exception Returned document contains HTML raised, clearing cache 2021-05-08 00:10:31 DEBUG (MainThread) [denonavr.foundation] Request error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/foundation.py", line 131, in async_identify_receiver xml = await self.api.async_get_xml( File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 90, in wrapper return await func(*args, *kwargs) File "/usr/local/lib/python3.8/site-packages/async_lru.py", line 237, in wrapped return (yield from asyncio.shield(fut, loop=_loop)) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 143, in async_get_xml self.check_xml_validity(request, xml_root) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 246, in check_xml_validity raise AvrInvalidResponseError( denonavr.exceptions.AvrInvalidResponseError: Returned document contains HTML 2021-05-08 00:10:31 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15:8080/goform/Deviceinfo.xml "HTTP/1.1 404 Not Found"

2021-05-08 00:10:46 DEBUG (SyncWorker_5) [homeassistant.components.nmap_tracker.device_tracker] Scanning 2021-05-08 00:10:46 DEBUG (MainThread) [denonavr.decorators] HTTP timeout exception on request <Request('GET', 'http://192.168.1.15:8080/goform/Deviceinfo.xml')> Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 61, in map_httpcore_exceptions yield File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 200, in aiter async for part in self._httpcore_stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/connection_pool.py", line 62, in aiter async for chunk in self.stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_bytestreams.py", line 91, in aiter async for chunk in self._aiterator: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 152, in _receive_response_data event = await self._receive_event(timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 169, in _receive_event data = await self.socket.read(self.READ_NUM_BYTES, timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_backends/asyncio.py", line 156, in read raise File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpcore/_exceptions.py", line 12, in map_exceptions raise to_exc(exc) from None httpcore.ReadTimeout

ol-iver commented 3 years ago

Thanks, there is PR for a fix of the timeout issue, I created a couple of hours ago.

j2deen commented 3 years ago

Thank you. Much appreciated will test when available and report back!

Stefano0042 commented 3 years ago

I'm an owner of SR5006 too, I can share exactly the same experience as J2deen, nice to see that you are on the solution, yet.

j2deen commented 3 years ago

Just tested with Home Assistant 2021.5.2 with the update. Unfortunately I have to report it still does not work. Was hoping. Here are the updated logs from 2021.5.2. debug from the run

2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 DEBUG (MainThread) [homeassistant.components.ssdp] Discovered denonavr at http://192.168.1.15:8080/description.xml 2021-05-11 01:48:15 INFO (SyncWorker_1) [homeassistant.loader] Loaded denonavr from homeassistant.components.denonavr

2021-05-11 01:49:38 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140137630038000] Sending {"id": 17, "type": "result", "success": true, "result": [{"flow_id": "8ec79dcbd8784930a49ab864d8945f53", "handler": "denonavr", "context": {"source": "ssdp", "unique_id": "SR5006-0006780E35A6", "title_placeholders": {"name": "marantz-amp"}, "confirm_only": true}, "step_id": "confirm"}]} 2021-05-11 01:49:38 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140137630038000] Sending {"id": 20, "type": "result", "success": true, "result": [{"flow_id": "8ec79dcbd8784930a49ab864d8945f53", "handler": "denonavr", "context": {"source": "ssdp", "unique_id": "SR5006-0006780E35A6", "title_placeholders": {"name": "marantz-amp"}, "confirm_only": true}, "step_id": "confirm"}]}

2021-05-11 01:49:38 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140137630038000] Received {'type': 'manifest/get', 'integration': 'denonavr', 'id': 35} 2021-05-11 01:49:38 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection] [140137630038000] Sending {"id": 35, "type": "result", "success": true, "result": {"domain": "denonavr", "name": "Denon AVR Network Receivers", "config_flow": true, "documentation": "https://www.home-assistant.io/integrations/denonavr", "requirements": ["denonavr==0.10.7"], "codeowners": ["@scarface-4711", "@starkillerOG"], "ssdp": [{"manufacturer": "Denon", "deviceType": "urn:schemas-upnp-org:device:MediaRenderer:1"}, {"manufacturer": "DENON", "deviceType": "urn:schemas-upnp-org:device:MediaRenderer:1"}, {"manufacturer": "DENON PROFESSIONAL", "deviceType": "urn:schemas-upnp-org:device:MediaRenderer:1"}, {"manufacturer": "Marantz", "deviceType": "urn:schemas-upnp-org:device:MediaRenderer:1"}, {"manufacturer": "Denon", "deviceType": "urn:schemas-upnp-org:device:MediaServer:1"}, {"manufacturer": "DENON", "deviceType": "urn:schemas-upnp-org:device:MediaServer:1"}, {"manufacturer": "DENON PROFESSIONAL", "deviceType": "urn:schemas-upnp-org:device:MediaServer:1"}, {"manufacturer": "Marantz", "deviceType": "urn:schemas-upnp-org:device:MediaServer:1"}, {"manufacturer": "Denon", "deviceType": "urn:schemas-denon-com:device:AiosDevice:1"}, {"manufacturer": "DENON", "deviceType": "urn:schemas-denon-com:device:AiosDevice:1"}, {"manufacturer": "DENON PROFESSIONAL", "deviceType": "urn:schemas-denon-com:device:AiosDevice:1"}, {"manufacturer": "Marantz", "deviceType": "urn:schemas-denon-com:device:AiosDevice:1"}], "iot_class": "local_polling", "is_built_in": true}}

2021-05-11 01:49:45 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15/goform/Deviceinfo.xml "HTTP/1.1 200 Data follows" 2021-05-11 01:49:45 DEBUG (MainThread) [denonavr.decorators] Exception Returned document contains HTML raised, clearing cache 2021-05-11 01:49:45 DEBUG (MainThread) [denonavr.foundation] Request error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/asyncstdlib/_lrucache.py", line 281, in wrapper result = cache[key] KeyError: <asyncstdlib._lrucache.CallKey object at 0x7f745f0abf40>

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/foundation.py", line 132, in async_identify_receiver xml = await self.api.async_get_xml( File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 96, in wrapper return await func(*args, kwargs) File "/usr/local/lib/python3.8/site-packages/asyncstdlib/_lrucache.py", line 284, in wrapper result = await function(*args, *kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(args, kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 143, in async_get_xml self.check_xml_validity(request, xml_root) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 246, in check_xml_validity raise AvrInvalidResponseError( denonavr.exceptions.AvrInvalidResponseError: Returned document contains HTML 2021-05-11 01:49:45 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15:8080/goform/Deviceinfo.xml "HTTP/1.1 404 Not Found"

2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.decorators] HTTP timeout exception on request <Request('GET', 'http://192.168.1.15:8080/goform/Deviceinfo.xml')> Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 61, in map_httpcore_exceptions yield File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 200, in aiter async for part in self._httpcore_stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/connection_pool.py", line 62, in aiter async for chunk in self.stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_bytestreams.py", line 91, in aiter async for chunk in self._aiterator: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 149, in _receive_response_data event = await self._receive_event(timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 166, in _receive_event data = await self.socket.read(self.READ_NUM_BYTES, timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_backends/asyncio.py", line 156, in read raise File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpcore/_exceptions.py", line 12, in map_exceptions raise to_exc(exc) from None httpcore.ReadTimeout

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(*args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 85, in async_get res = await client.get(endpoint, timeout=self.timeout) File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1662, in get return await self.request( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1426, in request response = await self.send( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1529, in send raise exc File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1520, in send await response.aread() File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in aread self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1578, in aiter_bytes async for raw_bytes in self.aiter_raw(): File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1632, in aiter_raw async for raw_stream_bytes in self.stream: File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 127, in aiter async for chunk in self._stream: File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 201, in aiter yield part File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 78, in map_httpcore_exceptions raise mapped_exc(message) from exc httpx.ReadTimeout 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.decorators] Exception TimeoutException: raised, clearing cache 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.foundation] Connection error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 61, in map_httpcore_exceptions yield File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 200, in aiter async for part in self._httpcore_stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/connection_pool.py", line 62, in aiter async for chunk in self.stream: File "/usr/local/lib/python3.8/site-packages/httpcore/_bytestreams.py", line 91, in aiter async for chunk in self._aiterator: File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 149, in _receive_response_data event = await self._receive_event(timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_async/http11.py", line 166, in _receive_event data = await self.socket.read(self.READ_NUM_BYTES, timeout) File "/usr/local/lib/python3.8/site-packages/httpcore/_backends/asyncio.py", line 156, in read raise File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpcore/_exceptions.py", line 12, in map_exceptions raise to_exc(exc) from None httpcore.ReadTimeout

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(*args, **kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 85, in async_get res = await client.get(endpoint, timeout=self.timeout) File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1662, in get return await self.request( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1426, in request response = await self.send( File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1529, in send raise exc File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 1520, in send await response.aread() File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in aread self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1562, in self._content = b"".join([part async for part in self.aiter_bytes()]) File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1578, in aiter_bytes async for raw_bytes in self.aiter_raw(): File "/usr/local/lib/python3.8/site-packages/httpx/_models.py", line 1632, in aiter_raw async for raw_stream_bytes in self.stream: File "/usr/local/lib/python3.8/site-packages/httpx/_client.py", line 127, in aiter async for chunk in self._stream: File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 201, in aiter yield part File "/usr/local/lib/python3.8/contextlib.py", line 131, in exit self.gen.throw(type, value, traceback) File "/usr/local/lib/python3.8/site-packages/httpx/_transports/default.py", line 78, in map_httpcore_exceptions raise mapped_exc(message) from exc httpx.ReadTimeout

The above exception was the direct cause of the following exception:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/foundation.py", line 132, in async_identify_receiver xml = await self.api.async_get_xml( File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 96, in wrapper return await func(*args, kwargs) File "/usr/local/lib/python3.8/site-packages/asyncstdlib/_lrucache.py", line 284, in wrapper result = await function(*args, *kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(args, kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 139, in async_get_xml res = await self.async_get(request) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 58, in wrapper raise AvrTimoutError( denonavr.exceptions.AvrTimoutError: TimeoutException: 2021-05-11 01:50:00 INFO (MainThread) [denonavr.foundation] AVR device, AppCommand.xml interface not supported 2021-05-11 01:50:00 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15:8080/description.xml "HTTP/1.1 200 OK" 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.ssdp] Device http://192.168.1.15:8080/description.xml has manufacturer Marantz 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetAllZonePowerStatus', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetAllZoneSource', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetRenameSource', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetDeletedSource', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetSurroundModeStatus', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetToneControl', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetAllZoneVolume', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='1', cmd_text='GetAllZoneMuteStatus', name=None, param_list=None, set_command=None, response_pattern=()) to AppCommand update tuple 2021-05-11 01:50:00 DEBUG (MainThread) [denonavr.api] Add tag AppCommandCmd(cmd_id='3', cmd_text=None, name='GetAudyssey', param_list=(AppCommandCmdParam(name='dynamiceq', text=''), AppCommandCmdParam(name='reflevoffset', text=''), AppCommandCmdParam(name='dynamicvol', text=''), AppCommandCmdParam(name='multeq', text='')), set_command=None, response_pattern=()) to AppCommand0300 update tuple 2021-05-11 01:50:00 ERROR (MainThread) [homeassistant.components.denonavr.receiver] Missing receiver information: manufacturer 'Marantz', name 'None', model 'SR5006', type 'avr' 2021-05-11 01:50:00 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=dismiss, service_data=notification_id=config_entry_discovery> 2021-05-11 01:50:00 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.config_entry_discovery, old_state=<state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, friendly_name=New devices discovered, message=We have discovered new devices on your network. Check it out. @ 2021-05-10T21:48:16.041366-04:00>, new_state=None>

2021-05-11 01:50:14 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15/goform/Deviceinfo.xml "HTTP/1.1 200 Data follows" 2021-05-11 01:50:14 DEBUG (MainThread) [denonavr.decorators] Exception Returned document contains HTML raised, clearing cache 2021-05-11 01:50:14 DEBUG (MainThread) [denonavr.foundation] Request error when identifying receiver Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/asyncstdlib/_lrucache.py", line 281, in wrapper result = cache[key] KeyError: <asyncstdlib._lrucache.CallKey object at 0x7f7455a3fa90>

During handling of the above exception, another exception occurred:

Traceback (most recent call last): File "/usr/local/lib/python3.8/site-packages/denonavr/foundation.py", line 132, in async_identify_receiver xml = await self.api.async_get_xml( File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 96, in wrapper return await func(*args, kwargs) File "/usr/local/lib/python3.8/site-packages/asyncstdlib/_lrucache.py", line 284, in wrapper result = await function(*args, *kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/decorators.py", line 44, in wrapper return await func(args, kwargs) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 143, in async_get_xml self.check_xml_validity(request, xml_root) File "/usr/local/lib/python3.8/site-packages/denonavr/api.py", line 246, in check_xml_validity raise AvrInvalidResponseError( denonavr.exceptions.AvrInvalidResponseError: Returned document contains HTML 2021-05-11 01:50:14 DEBUG (MainThread) [httpx._client] HTTP Request: GET http://192.168.1.15:8080/goform/Deviceinfo.xml "HTTP/1.1 404 Not Found"

j2deen commented 3 years ago

Please let me know if there is anything else I can do to help.

Stefano0042 commented 3 years ago

50288 confirmed, Marantz SR5006 does not get benefit from 2021.5.2.

ol-iver commented 3 years ago

That's now a different error message. The same as in this issue https://github.com/home-assistant/core/issues/50431