Integration does notwork anymore with Home Assistant 2024.01. For me it does not connect to the receiver anymore. There seems to be changes for media_player with the HA release. Many others like mopidy have also issues with the new release, e.g.:
state method in media_player changed from property to cached_property

Receiver is reachable via browser but integration says it cannot connect:
Cannot connect to AVR: [Errno 111] Connect call failed

Hi @crowbarz and thanks for this great idea of maintaining a single continuous telnet session to AVR.

I would like to use your component with my VSX-S510 but I have a Runtime Error: No input sources found on AVR.

Error setting up entry 192.168.1.123:8102 for pioneer_async Traceback (most recent call last): File "/usr/src/homeassistant/homeassistant/config_entries.py", line 222, in async_setup result = await component.async_setup_entry( # type: ignore File "/config/custom_components/pioneer_async/__init__.py", line 73, in async_setup_entry await pioneer.build_source_dict() File "/config/custom_components/pioneer_async/pioneer_avr.py", line 533, in build_source_dict raise RuntimeError("No input sources found on AVR") RuntimeError: No input sources found on AVR

Can I manually add input sources ?

Thanks

Hi,
i have a VSX 828 and its not working. I start the configuration, after some time it stops with the following error
`Logger: homeassistant.config_entries
Source: custom_components/pioneer_async/init.py:160
Integration: Pioneer AVR (documentation)
First occurred: 13:36:19 (1 occurrences)
Last logged: 13:36:19

Error setting up entry 192.168.178.30:8102 for pioneer_async
Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/config_entries.py", line 387, in async_setup
result = await component.async_setup_entry(hass, self)
File "/config/custom_components/pioneer_async/init.py", line 160, in async_setup_entry
await pioneer.build_source_dict()
File "/usr/local/lib/python3.10/site-packages/aiopioneer/pioneer_avr.py", line 766, in build_source_dict
response = await self.send_raw_request(
File "/usr/local/lib/python3.10/site-packages/aiopioneer/pioneer_avr.py", line 608, in send_raw_request
response = await self._read_response(timeout=self._timeout)
File "/usr/local/lib/python3.10/site-packages/aiopioneer/pioneer_avr.py", line 530, in _read_response
done, pending = await asyncio.wait( # pylint: disable=unused-variable
File "/usr/local/lib/python3.10/asyncio/tasks.py", line 384, in wait
return await _wait(fs, timeout, return_when, loop)
File "/usr/local/lib/python3.10/asyncio/tasks.py", line 491, in _wait
await waiter
asyncio.exceptions.CancelledError`

Can you try to fix it?

connecting via telnet to Port 8102 is working. Changing source and do other things are also workig. the avr is running at the moment of initialisation.

Version 0.8.6 stopped working completely unexpectedly today. Couldn't get it to work anymore.
Checked HACS and found out there was an update. But installation keeps goiing on forever...
Probably the error is somewhere on my site, but can't find out what it is.
No remaining references in configuration.yaml, removed them month's ago.

UPDATE: got a little further. Finally it installed. After reboot I could add the integration. But after filling out the detail in te configuration panel I get this error:
Pioneer AVR
Translation Error: The intl string context variable "exception" was not provided to the string "Unexpected exception: {exception}"

Any suggestions?

These models I note either have custom configuration or people have noted that they are using that model and we can assume it works. Are there any other models that people have used this on?

Compatible and Supported

* does not support sleep mode
? currently testing

NOT Compatible

Try using one of the Onkyo or onkyo-eiscp integrations instead.

Hallo,

I hope you can help me. I am a "normal" user in HA.
I have add the HACS ha-pioneer_async integration and die install wizard starts

I add the ip-adress and test some ports 8102, 23 and try some other ports there are open on the pioneer.
But I can´t integrate the receiver VSX-LX505.

I got this Error Message:
Cannot connect to AVR: [Errno 111] Connect call failed ('192.168.xxx.xxx', 8102)

2024-03-29 20:24:45.430 ERROR (MainThread) [custom_components.pioneer_async.config_flow] unexpected exception: AVR connection not available
2024-03-29 20:28:06.771 ERROR (MainThread) [aiopioneer.pioneer_avr] cannot execute system_query_mac_addr command: AVR connection not available
2024-03-29 20:28:06.772 ERROR (MainThread) [aiopioneer.pioneer_avr] cannot execute system_query_software_version command: AVR connection not available
2024-03-29 20:28:06.774 ERROR (MainThread) [custom_components.pioneer_async.config_flow] unexpected exception: AVR connection not available

Have someone a idee?
Where I can get more Logs?

Just updated to 0.8.1 from pre 0.8 and found this error in my log.

>> PioneerAVR._connection_listener() exception: string index out of range

Integration does stll seem to work though. So no clue what is wrong.
Using with a VSX-922.

Expose aiopioneer.send_command and possibly aiopioneer.send_raw_command as services so that commands not yet implemented can be used. Follow-up from crowbarz/aiopioneer#33.

After we refactored the parser in the latest update, a number of responses no longer have an associated zone which stops HA from picking up a large number of global attributes unless a zone specific attribute is updated (such as volume).

Raising this issue more as a note. @crowbarz I'm tempted to just add a check into _parse_response for aiopioneer and add zone 1 to updated_zones if the parser response zone was null. What do you think?

When adding a VSX1120, it adds the node to the devices list, during that process, it seems to detect the inputs correctly. Once added though, when its trying to "Initialize", it fails with the following error.

Logger: homeassistant.config_entries
Source: config_entries.py:1247
First occurred: June 8, 2023 at 6:44:12 PM (3 occurrences)
Last logged: 8:58:48 AM

Config entry '192.168.60.245:23' for pioneer_async integration not ready yet: Main Zone not found on AVR; Retrying in background

Since the update to the release 0.7.0 i am receiving the following errors from this integration:

Source: runner.py:128
First occurred: 27. Februar 2023 um 01:20:56 (53 occurrences)
Last logged: 28. Februar 2023 um 22:59:24

>> PioneerAVR._connection_listener() exception: invalid literal for int() with base 10: ''
>> PioneerAVR._connection_listener() exception: string index out of range

Despite these errors I did not find any problems with controlling my AVR.

I have installed the integration via HACS and configured my AVR in yaml, to increase the timeout (needed it for the previous release).

Seem to be seeing a few of these errors in my HA logs at the moment:

Logger: aiopioneer.pioneer_avr
Source: custom_components/pioneer_async/media_player.py:412
Integration: Pioneer AVR ([documentation](https://github.com/crowbarz/ha-pioneer_async))
First occurred: 16 August 2022 at 22:32:33 (8 occurrences)
Last logged: 21 August 2022 at 20:37:53

AVR command 073VL returned error: E02

It appears that this happens when the AVR is switched off which according to the docs that I have:

Perhaps a check needs to be added to ensure that the AVR is powered on?

Hi @crowbarz and thanks for your work.

I have a VSX-S510 and I got an error after updating to 0.1 via HACS.

My configuration.yaml

media_player:
  - platform: pioneer_async
    host: 192.168.1.123
    sources: { TV: '06', GCast: '15' }

Error Log

Error while setting up pioneer_async platform for media_player
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 201, in _async_setup_platform
    await asyncio.gather(*pending)
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 310, in async_add_entities
    await asyncio.gather(*tasks)
  File "/usr/src/homeassistant/homeassistant/helpers/entity_platform.py", line 356, in _async_add_entity
    if entity.unique_id is not None:
  File "/config/custom_components/pioneer_async/media_player.py", line 217, in unique_id
    return self._entry.unique_id + "/" + self._zone
AttributeError: 'NoneType' object has no attribute 'unique_id'

Debug

2020-11-03   16:18:50 DEBUG (MainThread) [custom_components.pioneer_async.pioneer_avr]   Opening AVR connection
2020-11-03 16:18:50 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr]   PioneerAVR.__init__(host="192.168.1.123", port=8102, timeout=2.0,   command_delay=0.1, volume_workaround=False)
2020-11-03 16:18:51 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] >>   PioneerAVR.connection_listener() started
2020-11-03 16:18:51 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?P
2020-11-03 16:18:51 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR connection established
2020-11-03 16:18:51 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Querying available zones on AVR
2020-11-03 16:18:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?P returned   response: PWR2
2020-11-03 16:18:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   PWR2
2020-11-03 16:18:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?AP
2020-11-03 16:18:52 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 1 discovered
2020-11-03 16:18:52 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 1: Power: False
2020-11-03 16:18:54 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?AP returned   response: APR1
2020-11-03 16:18:54 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   APR1
2020-11-03 16:18:54 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?BP
2020-11-03 16:18:54 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 2 discovered
2020-11-03 16:18:54 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 2: Power: False
2020-11-03 16:18:55 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:18:57 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?BP timed out
2020-11-03 16:18:57 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:18:57 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?ZEP
2020-11-03 16:19:01 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?ZEP timed out
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?M returned   response: MUT1
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?P returned   response: PWR2
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?V returned   response: VOL101
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   MUT1
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   PWR2
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   VOL101
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Delaying command for 0.015s
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Delaying command for 0.093s
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?F
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?M
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?P
2020-11-03 16:19:02 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?V
2020-11-03 16:19:02 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Updating AVR status (full=True,   last updated 4.710s ago)
2020-11-03 16:19:02 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 1: Mute: False
2020-11-03 16:19:02 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 1: Volume: 101
2020-11-03 16:19:05 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?F returned   response: FN06
2020-11-03 16:19:05 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   FN06
2020-11-03 16:19:05 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?AP
2020-11-03 16:19:05 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 1: Source: TV
2020-11-03 16:19:06 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?AP returned   response: APR1
2020-11-03 16:19:06 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   APR1
2020-11-03 16:19:06 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?ZV
2020-11-03 16:19:07 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?ZV returned   response: ZV00
2020-11-03 16:19:07 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   ZV00
2020-11-03 16:19:07 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?Z2M
2020-11-03 16:19:07 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 2: Volume: 0
2020-11-03 16:19:08 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?Z2M returned   response: Z2MUT1
2020-11-03 16:19:08 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   Z2MUT1
2020-11-03 16:19:08 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Sending AVR command: ?ZS
2020-11-03 16:19:08 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 2: Mute: False
2020-11-03 16:19:09 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR command ?ZS returned   response: Z2F04
2020-11-03 16:19:09 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   Z2F04
2020-11-03 16:19:09 INFO (MainThread)   [custom_components.pioneer_async.pioneer_avr] Zone 2: Source: 04
2020-11-03 16:19:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   0.003s ago
2020-11-03 16:19:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   0.004s ago
2020-11-03 16:19:39 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:19:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.083s ago
2020-11-03 16:20:09 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:20:39 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:20:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.083s ago
2020-11-03 16:21:09 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:21:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:21:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.084s ago
2020-11-03 16:22:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:22:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:22:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.085s ago
2020-11-03 16:23:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:23:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:23:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.086s ago
2020-11-03 16:24:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:24:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:24:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.085s ago
2020-11-03 16:25:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:25:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:25:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.086s ago
2020-11-03 16:26:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:26:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:26:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.088s ago
2020-11-03 16:27:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:27:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:27:52 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] Skipping update: last updated   12.084s ago
2020-11-03 16:28:10 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R
2020-11-03 16:28:40 DEBUG (MainThread)   [custom_components.pioneer_async.pioneer_avr] AVR listener received response:   R

Can you do something to solve this issue ?
Let me know if I can send you more information.

@11harveyj not sure if this is a side effect of my powered off Pioneer VSX-925.. everything works, just the endless logs

2023-05-28 13:28:12.559 INFO (MainThread) [custom_components.pioneer_async] config migration to version 2 successful
2023-05-28 13:28:12.560 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.4.2
2023-05-28 13:28:55.798 INFO (MainThread) [custom_components.pioneer_async] config migration to version 2 successful
2023-05-28 13:28:55.798 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.4.2
2023-05-28 13:30:19.358 INFO (MainThread) [custom_components.pioneer_async] config migration to version 2 successful
2023-05-28 13:30:19.359 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.4.2
2023-05-28 13:31:42.835 INFO (MainThread) [custom_components.pioneer_async] config migration to version 2 successful
2023-05-28 13:31:42.836 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.4.2
2023-05-28 13:33:06.226 INFO (MainThread) [custom_components.pioneer_async] config migration to version 2 successful
2023-05-28 13:33:06.226 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.4.2

It stops one my Pioneer is powered on. And does not seem to repeat until the next reboot of HA

Hi, Cool stuff! Unfortunately I fail to properly configure my VSX-2021. I installed ha-pioneer async repo via HACS and was able to add and install via Integrations menu but at the end shows a "Failed to setup" integration tile.

I am able to successfully implement the original Pioneer integration (but would like to replace due to instability issues)

A telnet session to my AVR shows:
user@system ~]$ telnet 10.100.15.22 8102
Trying 10.100.15.22...
Connected to 10.100.15.22.
Escape character is '^]'.
PWR1
PWR1
PWR1

I wonder if there is some sort of incompatibility issue with this specific model.

Error setting up entry 10.100.15.22:8102 for pioneer_async

Traceback (most recent call last):
File "/usr/src/homeassistant/homeassistant/config_entries.py", line 293, in async_setup
result = await component.async_setup_entry(hass, self) # type: ignore
File "/config/custom_components/pioneer_async/init.py", line 80, in async_setup_entry
await pioneer.connect()
File "/usr/local/lib/python3.8/site-packages/aiopioneer/pioneer_avr.py", line 253, in connect
reader, writer = await asyncio.wait_for( # pylint: disable=unused-variable
File "/usr/local/lib/python3.8/asyncio/tasks.py", line 494, in wait_for
return fut.result()
File "/usr/local/lib/python3.8/asyncio/streams.py", line 52, in open_connection
transport, _ = await loop.create_connection(
File "/usr/local/lib/python3.8/asyncio/base_events.py", line 1025, in create_connection
raise exceptions[0]
File "/usr/local/lib/python3.8/asyncio/base_events.py", line 1010, in create_connection
sock = await self._connect_sock(
File "/usr/local/lib/python3.8/asyncio/base_events.py", line 924, in _connect_sock
await self.sock_connect(sock, address)
File "/usr/local/lib/python3.8/asyncio/selector_events.py", line 496, in sock_connect
return await fut
File "/usr/local/lib/python3.8/asyncio/selector_events.py", line 528, in _sock_connect_cb
raise OSError(err, f'Connect call failed {address}')
ConnectionRefusedError: [Errno 111] Connect call failed ('10.100.15.22', 8102)

Clicking on Configure from Intergration Screen generates:

Error
Config flow could not be loaded: 500 Internal Server Error Server got itself in trouble

2023-03-01 21:52:02.061 ERROR (MainThread) [aiohttp.server] Error handling request
Traceback (most recent call last):
  File "/usr/local/lib/python3.10/site-packages/aiohttp/web_protocol.py", line 433, in _handle_request
    resp = await request_handler(request)
  File "/usr/local/lib/python3.10/site-packages/aiohttp/web_app.py", line 504, in _handle
    resp = await handler(request)
  File "/usr/local/lib/python3.10/site-packages/aiohttp/web_middlewares.py", line 117, in impl
    return await handler(request)
  File "/usr/src/homeassistant/homeassistant/components/http/security_filter.py", line 67, in security_filter_middleware
    return await handler(request)
  File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 100, in forwarded_middleware
    return await handler(request)
  File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 28, in request_context_middleware
    return await handler(request)
  File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 235, in auth_middleware
    return await handler(request)
  File "/usr/src/homeassistant/homeassistant/components/http/view.py", line 146, in handle
    result = await result
  File "/usr/src/homeassistant/homeassistant/components/config/config_entries.py", line 218, in post
    return await super().post(request)
  File "/usr/src/homeassistant/homeassistant/components/http/data_validator.py", line 72, in wrapper
    result = await method(view, request, data, *args, **kwargs)
  File "/usr/src/homeassistant/homeassistant/helpers/data_entry_flow.py", line 71, in post
    result = await self._flow_mgr.async_init(
  File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 243, in async_init
    result = await self._async_handle_step(flow, flow.init_step, data)
  File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 367, in _async_handle_step
    result: FlowResult = await getattr(flow, method)(user_input)
  File "/config/custom_components/pioneer_async/config_flow.py", line 126, in async_step_init
    pioneer = self.hass.data[DOMAIN][entry.entry_id]
KeyError: 'f692b4d6060dbdc7b53b0d1d00d4c8bb'

I do not have config or settings in configuration.yaml. All configuration was done via your UI. (Maybe that releated the 'other open issue'. So I assume it is pulling that from an internal database of HA.

And my Pioneer was not on, so it could not make a connection to it to get full init or a re-init. (I have not checked the code yet)

This occurs for Pioneer which is fully powered off (not standby) at the time of boot.

I get these errors:

Entity media_player.vsx_528_2 (<class 'custom_components.pioneer_async.media_player.PioneerZone'>) implements device_state_attributes. Please report it to the custom component author.
Entity media_player.vsx_528_zone_2_2 (<class 'custom_components.pioneer_async.media_player.PioneerZone'>) implements device_state_attributes. Please report it to the custom component author.

Hi,

i installed the integration via HACS (v0.8.4) and then tried to add the integration in Settings->Devices & Services. Unfortunately the process get stuck on "Please wait, starting configuration wizard for pioneer_async".

Kind regards

Hi,

I finally have HACS installed, but what repository should i add to get my VSX-933 working?

Not sure what’s wrong.
I installed through HACS and can see the integration in the flow. The setup queries for the name, address and port. Once provided, it fails after a while with a “fail to set up error”

Log try:

Logger: aiohttp.server
Source: custom_components/pioneer_async/config_flow.py:125
Integration: Pioneer AVR (documentation)
First occurred: June 4, 2022, 11:32:58 PM (1 occurrences)
Last logged: June 4, 2022, 11:32:58 PM

Error handling request
Traceback (most recent call last):
File "/usr/local/lib/python3.9/site-packages/aiohttp/web_protocol.py", line 435, in _handle_request
resp = await request_handler(request)
File "/usr/local/lib/python3.9/site-packages/aiohttp/web_app.py", line 504, in _handle
resp = await handler(request)
File "/usr/local/lib/python3.9/site-packages/aiohttp/web_middlewares.py", line 117, in impl
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/security_filter.py", line 60, in security_filter_middleware
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/forwarded.py", line 100, in forwarded_middleware
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/request_context.py", line 28, in request_context_middleware
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/ban.py", line 79, in ban_middleware
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/auth.py", line 220, in auth_middleware
return await handler(request)
File "/usr/src/homeassistant/homeassistant/components/http/view.py", line 137, in handle
result = await result
File "/usr/src/homeassistant/homeassistant/components/config/config_entries.py", line 243, in post
return await super().post(request)
File "/usr/src/homeassistant/homeassistant/components/http/data_validator.py", line 62, in wrapper
result = await method(view, request, *args, **kwargs)
File "/usr/src/homeassistant/homeassistant/helpers/data_entry_flow.py", line 70, in post
result = await self._flow_mgr.async_init(
File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 205, in async_init
flow, result = await task
File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 232, in _async_init
result = await self._async_handle_step(flow, flow.init_step, data, init_done)
File "/usr/src/homeassistant/homeassistant/data_entry_flow.py", line 335, in _async_handle_step
result: FlowResult = await getattr(flow, method)(user_input)
File "/config/custom_components/pioneer_async/config_flow.py", line 125, in async_step_init
pioneer = self.hass.data[DOMAIN][entry.entry_id]
KeyError: 'f12187e9456b5754c7ca934fe6737fcb'

A minor issue, but while looking at pioneer_avr.py in line 1728, it reads:
"cannot calculate tuner AM frequency step: unable to step volume"
Instead of volume it should be either tuner or frequency.

When trying to set the Sound mode I get the following:

Running the current release 0.8

I can change the Source, but the error appears on Sound source. I think it worked in the previous release.

2023-04-08 20:34:03.165 ERROR (MainThread) [homeassistant.components.websocket_api.http.connection] [140087147918432] 'NoneType' object has no attribute 'get'
Traceback (most recent call last):
  File "/usr/src/homeassistant/homeassistant/components/websocket_api/commands.py", line 199, in handle_call_service
    await hass.services.async_call(
  File "/usr/src/homeassistant/homeassistant/core.py", line 1820, in async_call
    task.result()
  File "/usr/src/homeassistant/homeassistant/core.py", line 1857, in _execute_service
    await cast(Callable[[ServiceCall], Awaitable[None]], handler.job.target)(
  File "/usr/src/homeassistant/homeassistant/helpers/entity_component.py", line 216, in handle_service
    await service.entity_service_call(
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 798, in entity_service_call
    future.result()  # pop exception if have
  File "/usr/src/homeassistant/homeassistant/helpers/entity.py", line 977, in async_request_call
    await coro
  File "/usr/src/homeassistant/homeassistant/helpers/service.py", line 838, in _handle_entity_call
    await result
  File "/config/custom_components/pioneer_async/media_player.py", line 636, in async_select_sound_mode
    return await self._pioneer.set_listening_mode(sound_mode, self._zone)
  File "/usr/local/lib/python3.10/site-packages/aiopioneer/pioneer_avr.py", line 1370, in set_listening_mode
    if self.audio.get(zone).get("input_multichannel"):
AttributeError: 'NoneType' object has no attribute 'get'

Current Attribute state for the media player is:

sound_mode_list: STEREO, Front Stage Surround Advance, Front Stage Surround Advance Wide, AUTO SURR/STREAM DIRECT, AUTO SURROUND, DIRECT, PURE DIRECT, STEREO (direct), STANDARD, 2ch, PRO LOGIC, PRO LOGIC2 MOVIE, PRO LOGIC2 MUSIC, PRO LOGIC2 GAME, Neo:6 CINEMA, Neo:6 MUSIC, PRO LOGIC2x MOVIE, PRO LOGIC2x MUSIC, PRO LOGIC2x GAME, XM HD SURROUND, NEURAL SURROUND, PRO LOGIC2z HEIGHT, WIDE SURROUND MOVIE, WIDE SURROUND MUSIC, Neo:X CINEMA, Neo:X MUSIC, Neo:X GAME, NEURAL SURROUND+Neo:X CINEMA, NEURAL SURROUND+Neo:X MUSIC, NEURAL SURROUND+Neo:X GAME, PROLOGIC + THX CINEMA, PL2 MOVIE + THX CINEMA, Neo:6 CINEMA + THX CINEMA, PL2x MOVIE + THX CINEMA, THX SELECT2 GAMES, THX CINEMA, THX MUSIC, THX GAMES, PL2 MUSIC + THX MUSIC, PL2x MUSIC + THX MUSIC, Neo:6 MUSIC + THX MUSIC, PL2 GAME + THX GAMES, PL2x GAME + THX GAMES, THX ULTRA2 GAMES, PROLOGIC + THX MUSIC, PROLOGIC + THX GAMES, PL2z HEIGHT + THX CINEMA, PL2z HEIGHT + THX MUSIC, PL2z HEIGHT + THX GAMES, ACTION, SCI-FI, DRAMA, ENTERTAINMENT SHOW, MONO FILM, EXPANDED THEATER, CLASSICAL, UNPLUGGED, ROCK/POP, EXTENDED STEREO, PHONES SURROUND, TV SURROUND, SPORTS, ADVANCED GAME, Auto Level Control, OPTIMUM SURROUND, RETRIEVER AIR, ECO MODE, Neo:X CINEMA + THX CINEMA, Neo:X MUSIC + THX MUSIC, Neo:X GAME + THX GAMES, ECO MODE 1, ECO MODE 2
device_class: receiver
friendly_name: Pioneer AVR
supported_features: 69004
source_list: PHONO, CD, TUNER, CD-R/TAPE, Wii, TV/SAT, VIDEO 1, MULTI CH IN, VIDEO 2, DVR/BDR, iPod/USB, XM RADIO, CabIe, Computer, Amazon FireTV, HDMI 4, HDMI 5, Bluray, INTERNET RADIO, SIRIUS, ADAPTER PORT
volume_level: 0.4648648648648649
is_volume_muted: false
media_title: TV/SAT
source: TV/SAT
sound_mode: 
sources_json: {"PHONO": "00", "CD": "01", "TUNER": "02", "CD-R/TAPE": "03", "Wii": "04", "TV/SAT": "05", "VIDEO 1": "10", "MULTI CH IN": "12", "VIDEO 2": "14", "DVR/BDR": "15", "iPod/USB": "17", "XM RADIO": "18", "CabIe": "19", "Computer": "20", "Amazon FireTV": "21", "HDMI 4": "22", "HDMI 5": "23", "Bluray": "25", "INTERNET RADIO": "26", "SIRIUS": "27", "ADAPTER PORT": "33"}
device_volume: 86
device_max_volume: 185
device_volume_db: -37.5
amp: 
speakers: A
hdmi_audio: AMP
pqls: AUTO
panel_lock: 'OFF'
remote_lock: '0'
display: "\0              "

tuner: 
band: FM
frequency: 99.1
class: A
preset: 1

channel_levels: 
'1':
  C: 7
  L: 8.5
  R: 9
  SL: 9
  SR: 8.5
  SBL: 8.5
  SBR: 5.5
  SW: 1.5
  LH: 0
  RH: 0
  LW: 0
  RW: 0

dsp: 
mcacc_memory_set: 1
phase_control: 'off'
virtual_sb: true
virtual_height: true

video: 
signal_input_terminal: VIDEO
signal_input_resolution: '---'
signal_input_aspect: '---'
signal_input_color_format: '---'
signal_input_bit: '---'
signal_input_extended_colorspace: '---'
signal_output_resolution: '---'
signal_output_aspect: '---'
signal_output_color_format: '---'
signal_output_bit: '---'
signal_output_extended_colorspace: '---'
signal_hdmi1_recommended_resolution: '---'
signal_hdmi1_deepcolor: 36bit (12bit*3)
signal_hdmi2_recommended_resolution: '---'
signal_hdmi2_deepcolor: '---'

Hi,

my integration works well the last months. But after some days the status not changed. today i rebooted my home assistant. If if take a look under integration, i see that the entity will not be provided by pioneer_async anymore.
Home assistant is the actual version 2023.8.4.

i configured it manually through config file like this.

`media_player:

• platform: pioneer_async
  name: Pioneer AVR
  host: 192.168.178.30
  port: 8102
  scan_interval: 60
  timeout: 5.0
  sources: { TV: '05', SAT: '06', GAME: '49' }`

Follow-up from crowbarz/aiopioneer#33

From https://community.home-assistant.io/t/pioneer-avr-integration-supporting-config-flow-and-asyncio/221547/41

I’m getting the same “AVR “192.168.0.106:8102” is already configured” after a reboot. Reinstalling it fixes it until the next reboot it seems.

Any tips? Pretty new to Home Assistant but will help where I can.

Hi - and thanks for your great work! I got a Pioneer receiver using your addon and it's mostly working great. I noticed that media_player.volume_set doesn't work on the player. This means I can't use a slider to control the volume or make it work with Google Assistant. Am I missing something?

I have a Pioneer VSX-925 (2010) and it does not have a lower power standby mode for the network module. Therefor when the Pioneer is turn off, the entity is marked as unknown. Can we add a parameter (or model configuration) to indicate that power off vs standby mode. This is the best Pioneer integration out there!

Also, to know it does not support ?RGD, ?SVB or ?SSI commands.

History

Pioneer AVR turned on
8:48:24 AM - 2 hours ago
Pioneer AVR became unavailable triggered by automation Receiver off with TV triggered by state of Sony XR-65X90J
8:38:09 AM - 2 hours ago
Pioneer AVR turned on
8:37:58 AM - 2 hours ago

Debug

2023-02-23 21:11:15.270 DEBUG (MainThread) [custom_components.pioneer_async.media_player] Configuring AVR "192.168.4.44:8102"
2023-02-23 21:11:15.270 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.1.8
2023-02-23 21:11:15.270 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.init(host="192.168.4.44", port=8102, timeout=2, params={'ignored_zones': ['2']})
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.set_user_params({'ignored_zones': ['2']})
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-23 21:11:15.720 INFO (MainThread) [aiopioneer.pioneer_avr] AVR connection established
2023-02-23 21:11:15.720 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._listener_schedule()
2023-02-23 21:11:15.721 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._connection_listener() started
2023-02-23 21:11:15.721 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._updater_schedule()
2023-02-23 21:11:15.733 INFO (MainThread) [aiopioneer.pioneer_avr] updating AVR status (full=True, last updated 1677211875.733 s ago)
2023-02-23 21:11:15.733 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() completed
2023-02-23 21:11:15.733 INFO (MainThread) [aiopioneer.pioneer_avr] querying device information from Pioneer AVR
2023-02-23 21:11:15.733 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?RGD
2023-02-23 21:11:15.788 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?RGD returned error: E06
2023-02-23 21:11:15.788 DEBUG (MainThread) [aiopioneer.pioneer_avr] delaying command for 0.045 s
2023-02-23 21:11:17.383 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?SVB
2023-02-23 21:11:17.630 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?SVB returned error: E04
2023-02-23 21:11:17.630 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?SSI
2023-02-23 21:11:17.856 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?SSI returned error: E04
2023-02-23 21:11:17.856 INFO (MainThread) [aiopioneer.pioneer_avr] querying available zones on AVR
2023-02-23 21:11:17.856 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?P
2023-02-23 21:11:17.925 INFO (MainThread) [aiopioneer.pioneer_avr] Zone 1: Power: True

...

2023-02-23 21:11:21.566 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?RGB60
2023-02-23 21:11:21.571 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?RGB60 returned error: E06
2023-02-23 21:11:21.571 DEBUG (MainThread) [aiopioneer.pioneer_avr] source name->id: {'PHONO': '00', 'CD': '01', 'TUNER': '02', 'CD-R/TAPE': '03', 'Wii': '04', 'TV/SAT': '05', 'VIDEO 1': '10', 'MULTI CH IN': '12', 'VIDEO 2': '14', 'DVR/BDR': '15', 'iPod/USB': '17', 'XM RADIO': '18', 'CabIe': '19', 'Computer': '20', 'Amazon FireTV': '21', 'HDMI 4': '22', 'HDMI 5': '23', 'Bluray': '25', 'INTERNET RADIO': '26', 'SIRIUS': '27', 'ADAPTER PORT': '33'}
2023-02-23 21:11:21.571 DEBUG (MainThread) [aiopioneer.pioneer_avr] source id->name: {'00': 'PHONO', '01': 'CD', '02': 'TUNER', '03': 'CD-R/TAPE', '04': 'Wii', '05': 'TV/SAT', '10': 'VIDEO 1', '12': 'MULTI CH IN', '14': 'VIDEO 2', '15': 'DVR/BDR', '17': 'iPod/USB', '18': 'XM RADIO', '19': 'CabIe', '20': 'Computer', '21': 'Amazon FireTV', '22': 'HDMI 4', '23': 'HDMI 5', '25': 'Bluray', '26': 'INTERNET RADIO', '27': 'SIRIUS', '33': 'ADAPTER PORT'}
2023-02-23 21:11:21.574 DEBUG (MainThread) [custom_components.pioneer_async.media_player] >> async_setup_entry(entry_id=f692b4d6060dbdc7b53b0d1d00d4c8bb, data={'name': 'Pioneer AVR', 'host': '192.168.4.44', 'port': 8102}, options={'ignore_zone_2': True, 'ignored_zones': ['2']})
2023-02-23 21:11:21.575 INFO (MainThread) [custom_components.pioneer_async.media_player] Adding entities for zones ['1']
2023-02-23 21:11:21.575 DEBUG (MainThread) [custom_components.pioneer_async.media_player] PioneerZone.init(1)
2023-02-23 21:11:21.575 DEBUG (MainThread) [custom_components.pioneer_async.media_player] Created entity Pioneer AVR for zone 1
2023-02-23 21:11:21.578 DEBUG (MainThread) [custom_components.pioneer_async.media_player] >> PioneerZone.async_update(1)
2023-02-23 21:11:21.579 DEBUG (MainThread) [custom_components.pioneer_async.media_player] >> PioneerZone.async_added_to_hass(1)

...

2023-02-23 21:11:15.270 DEBUG (MainThread) [custom_components.pioneer_async.media_player] Configuring AVR "192.168.4.44:8102"
2023-02-23 21:11:15.270 INFO (MainThread) [aiopioneer.pioneer_avr] Starting aiopioneer 0.1.8
2023-02-23 21:11:15.270 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.init(host="192.168.4.44", port=8102, timeout=2, params={'ignored_zones': ['2']})
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.set_user_params({'ignored_zones': ['2']})
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-23 21:11:15.271 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-23 21:11:15.720 INFO (MainThread) [aiopioneer.pioneer_avr] AVR connection established
2023-02-23 21:11:15.720 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._listener_schedule()
2023-02-23 21:11:15.721 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._connection_listener() started
2023-02-23 21:11:15.721 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._updater_schedule()
2023-02-23 21:11:15.733 INFO (MainThread) [aiopioneer.pioneer_avr] updating AVR status (full=True, last updated 1677211875.733 s ago)
2023-02-23 21:11:15.733 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() completed
2023-02-23 21:11:15.733 INFO (MainThread) [aiopioneer.pioneer_avr] querying device information from Pioneer AVR
2023-02-23 21:11:15.733 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?RGD
2023-02-23 21:11:15.788 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?RGD returned error: E06
2023-02-23 21:11:15.788 DEBUG (MainThread) [aiopioneer.pioneer_avr] delaying command for 0.045 s
2023-02-23 21:11:17.383 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?SVB
2023-02-23 21:11:17.630 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?SVB returned error: E04
2023-02-23 21:11:17.630 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?SSI
2023-02-23 21:11:17.856 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?SSI returned error: E04
2023-02-23 21:11:17.856 INFO (MainThread) [aiopioneer.pioneer_avr] querying available zones on AVR
2023-02-23 21:11:17.856 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?P
2023-02-23 21:11:17.925 INFO (MainThread) [aiopioneer.pioneer_avr] Zone 1: Power: True

2023-02-25 08:45:03.577 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 08:45:03.577 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 08:45:05.578 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 08:45:05.578 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 64s before retrying connection
2023-02-25 08:46:10.521 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 08:46:10.522 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 08:46:12.523 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 08:46:12.523 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 64s before retrying connection
2023-02-25 08:47:17.257 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 08:47:17.257 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 08:47:19.258 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 08:47:19.258 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 64s before retrying connection
2023-02-25 08:48:23.864 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 08:48:23.864 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 08:48:23.978 INFO (MainThread) [aiopioneer.pioneer_avr] AVR connection established
2023-02-25 08:48:23.978 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._listener_schedule()
2023-02-25 08:48:23.978 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._connection_listener() started
2023-02-25 08:48:23.978 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._updater_schedule()
2023-02-25 08:48:23.979 INFO (MainThread) [aiopioneer.pioneer_avr] updating AVR status (full=True, last updated 615.495 s ago)
2023-02-25 08:48:23.979 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?P
2023-02-25 08:48:23.979 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() completed
2023-02-25 08:48:23.979 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.reconnect() completed
2023-02-25 08:48:23.988 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?P returned response: PWR0
2023-02-25 08:48:23.988 DEBUG (MainThread) [aiopioneer.pioneer_avr] delaying command for 0.091 s
2023-02-25 08:48:24.079 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?V
2023-02-25 08:48:24.089 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?V returned response: VOL102
2023-02-25 08:48:24.090 DEBUG (MainThread) [aiopioneer.pioneer_avr] delaying command for 0.090 s
2023-02-25 08:48:24.180 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?M
2023-02-25 08:48:24.190 INFO (MainThread) [aiopioneer.pioneer_avr] Zone 1: Mute: False
2023-02-25 08:48:24.190 DEBUG (MainThread) [aiopioneer.pioneer_avr] calling callback for zone 1
2023-02-25 08:48:24.191 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?M returned response: MUT1
2023-02-25 08:48:24.191 DEBUG (MainThread) [aiopioneer.pioneer_avr] delaying command for 0.090 s
2023-02-25 08:48:24.282 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: ?F
2023-02-25 08:48:24.288 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command ?F returned response: FN05
2023-02-25 08:48:24.288 DEBUG (MainThread) [aiopioneer.pioneer_avr] calling callback for zone 1
2023-02-25 10:27:28.805 INFO (MainThread) [aiopioneer.pioneer_avr] Zone 1: Mute: True
2023-02-25 10:27:28.805 DEBUG (MainThread) [aiopioneer.pioneer_avr] calling callback for zone 1
2023-02-25 10:27:34.148 DEBUG (MainThread) [aiopioneer.pioneer_avr] sending AVR command: PF
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.pioneer_avr] Task-1521671: connection closed
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.disconnect() started
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.pioneer_avr] disconnecting AVR connection
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.pioneer_avr] calling callback for zone 1
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(listener): requesting cancellation
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(listener): waiting for completion
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(listener): cancelled exception
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(responder): already completed
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(responder): returned exception: 0 bytes read on a total of undefined expected bytes
2023-02-25 10:27:34.896 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(updater): requesting cancellation
2023-02-25 10:27:34.897 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(updater): waiting for completion
2023-02-25 10:27:34.897 DEBUG (MainThread) [aiopioneer.pioneer_avr] Task-1587710: connection closed
2023-02-25 10:27:34.897 DEBUG (MainThread) [aiopioneer.pioneer_avr] AVR command PF timed out
2023-02-25 10:27:34.898 DEBUG (MainThread) [aiopioneer.util] >> cancel_task(updater): completed
2023-02-25 10:27:34.898 DEBUG (MainThread) [aiopioneer.pioneer_avr] closing AVR connection
2023-02-25 10:27:34.899 INFO (MainThread) [aiopioneer.pioneer_avr] AVR connection closed
2023-02-25 10:27:34.899 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._reconnect_schedule()
2023-02-25 10:27:34.899 INFO (MainThread) [aiopioneer.pioneer_avr] reconnecting to AVR
2023-02-25 10:27:34.899 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.disconnect() completed
2023-02-25 10:27:34.899 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR._connection_listener() completed
2023-02-25 10:27:34.899 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.reconnect() started
2023-02-25 10:27:34.899 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 4s before retrying connection
2023-02-25 10:27:39.258 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:27:39.258 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:27:41.259 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:27:41.259 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 4s before retrying connection
2023-02-25 10:27:45.508 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:27:45.508 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:27:47.510 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:27:47.510 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 4s before retrying connection
2023-02-25 10:27:51.702 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:27:51.702 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:27:53.703 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:27:53.704 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 8s before retrying connection
2023-02-25 10:28:02.205 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:28:02.205 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:28:04.206 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:28:04.206 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 16s before retrying connection
2023-02-25 10:28:20.715 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:28:20.715 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:28:22.716 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:28:22.716 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 32s before retrying connection
2023-02-25 10:28:54.842 DEBUG (MainThread) [aiopioneer.pioneer_avr] >> PioneerAVR.connect() started
2023-02-25 10:28:54.843 DEBUG (MainThread) [aiopioneer.pioneer_avr] opening AVR connection
2023-02-25 10:28:56.843 DEBUG (MainThread) [aiopioneer.pioneer_avr] could not reconnect to AVR: TimeoutError:
2023-02-25 10:28:56.844 DEBUG (MainThread) [aiopioneer.pioneer_avr] waiting 64s before retrying connection