Hi @andan ,
great to see that you have continued with the Sony Devices Binding and updated for OH3.
Some years ago I was supporting @tmrobert8 to get the ircc Autodiscovery for the AV receivers working.
https://community.openhab.org/t/sony-devices-binding/14052/182?u=oliverk
As I had replaced my Sony STR-DN1030 in the living room about 3 years ago I was not using this binding for a while now. Today I wanted to setup my good old Sony in our basement and decided to get the AVR again integrated into my openhab 3.2.0-1 using your new binding.
After enabling autodiscovery for ircc devices in `services\runtime.cfg’ by
discovery.sony-ircc:background=true
the AVR is immediately being discovered by the sony binding and added to the INBOX
When “adding as thing” from the inbox, the AVR shows up as Online in UI:
However, it seems like the binding is not successfully registering itself (as mediaremote) at the AVR:
2022-03-04 13:10:46.215 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found service: urn:upnp-org:serviceId:RenderingControl -- org.openhab.binding.sony.internal.upnp.models.UpnpService@2ef58f
2022-03-04 13:10:46.218 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and getting SCPD: http://192.168.205.104:8080/RenderingControl/desc.xml
2022-03-04 13:10:46.256 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and adding SCPD: http://192.168.205.104:8080/RenderingControl/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@19ec46a
2022-03-04 13:10:46.258 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found service: urn:upnp-org:serviceId:ConnectionManager -- org.openhab.binding.sony.internal.upnp.models.UpnpService@15de957
2022-03-04 13:10:46.260 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and getting SCPD: http://192.168.205.104:8080/ConnectionManager/desc.xml
2022-03-04 13:10:46.287 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and adding SCPD: http://192.168.205.104:8080/ConnectionManager/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@b8cfcc
2022-03-04 13:10:46.289 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found service: urn:upnp-org:serviceId:AVTransport -- org.openhab.binding.sony.internal.upnp.models.UpnpService@1dec96c
2022-03-04 13:10:46.291 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and getting SCPD: http://192.168.205.104:8080/AVTransport/desc.xml
2022-03-04 13:10:46.337 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and adding SCPD: http://192.168.205.104:8080/AVTransport/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@5cd121
2022-03-04 13:10:46.338 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found service: urn:schemas-sony-com:serviceId:Party -- org.openhab.binding.sony.internal.upnp.models.UpnpService@1280c50
2022-03-04 13:10:46.340 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and getting SCPD: http://192.168.205.104:8080/Party_scpd.xml
2022-03-04 13:10:46.369 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and adding SCPD: http://192.168.205.104:8080/Party_scpd.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@30f80b
2022-03-04 13:10:46.370 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found service: urn:schemas-sony-com:serviceId:IRCC -- org.openhab.binding.sony.internal.upnp.models.UpnpService@1b39e1d
2022-03-04 13:10:46.372 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and getting SCPD: http://192.168.205.104:8080/IRCCSCPD.xml
2022-03-04 13:10:46.395 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and adding SCPD: http://192.168.205.104:8080/IRCCSCPD.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@79b8af
2022-03-04 13:10:46.397 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and finding action: http://192.168.205.104:50001/cers/ActionList.xml
2022-03-04 13:10:46.712 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found action: http://192.168.205.104:50001/cers/ActionList.xml -- org.openhab.binding.sony.internal.ircc.models.IrccActionList@14d23b0
2022-03-04 13:10:46.714 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and getting system information: http://192.168.205.104:50001/cers/getSystemInformation
2022-03-04 13:10:47.443 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found system information: http://192.168.205.104:50001/cers/getSystemInformation -- org.openhab.binding.sony.internal.ircc.models.IrccSystemInformation@1960393
2022-03-04 13:10:47.447 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and getting remote commands: http://192.168.205.104:50001/cers/getRemoteCommandList
2022-03-04 13:10:47.524 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and encountered an error getting remote commands (using default now): 403 (http reason: Forbidden)
2022-03-04 13:10:47.528 [DEBUG] [nternal.transports.SonyHttpTransport] - Closing http client
2022-03-04 13:10:47.535 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 192.168.205.104, baseUri: /description.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1730/0x5d322a28@158504a with initial authorization cookie: auth="";Version=1
2022-03-04 13:10:47.613 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Command map already defined - ignoring: /etc/openhab/transform/ircc-5453edf73098.map
2022-03-04 13:10:47.615 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-03-04 13:10:47.617 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-03-04 13:10:47.622 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-03-04 13:10:47.624 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-03-04 13:10:47.626 [DEBUG] [nding.sony.internal.ircc.IrccHandler] - IRCC System now connected
2022-03-04 13:11:17.626 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 192.168.205.104:8080 - successful
2022-03-04 13:11:17.637 [DEBUG] [y.internal.transports.SonyAuthFilter] - Trying to renew our authorization cookie for host: 192.168.205.104
2022-03-04 13:11:17.666 [DEBUG] [y.internal.transports.SonyAuthFilter] - No authorization cookie was returned
2022-03-04 13:11:17.720 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Unknown code from getStatus: 403 (http reason: Forbidden)
I tried already various combination of discovering/adding/changing access code of the thing while I had put the Sony STR-DN1030 into pairing Mode without success:
Settings/Network/Media Remote Device/Start Registration
When I compare the DEBUG output of the binding with the steps in the manual approach required to pair openhab as mediaremote at the AVR, it looks like the last “register” command is not being performed:
(http://192.168.205.46:50001/cers/register?name=openHAB%20Sony®istrationType=initial&deviceId=MediaRemote%3Ab8-27-eb-fb-3f-15)
Output Binding
2022-03-04 13:10:46.397 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and finding action: http://192.168.205.104:50001/cers/ActionList.xml
2022-03-04 13:10:46.712 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found action: http://192.168.205.104:50001/cers/ActionList.xml -- org.openhab.binding.sony.internal.ircc.models.IrccActionList@14d23b0
2022-03-04 13:10:46.714 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and getting system information: http://192.168.205.104:50001/cers/getSystemInformation
2022-03-04 13:10:47.443 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and found system information: http://192.168.205.104:50001/cers/getSystemInformation -- org.openhab.binding.sony.internal.ircc.models.IrccSystemInformation@1960393
2022-03-04 13:10:47.447 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and getting remote commands: http://192.168.205.104:50001/cers/getRemoteCommandList
2022-03-04 13:10:47.524 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://192.168.205.104:8080/description.xml and encountered an error getting remote commands (using default now): 403 (http reason: Forbidden)
Do you have any idea how to get my AVR registered again with the new version of this binding?