Sony Devices Binding for OH3

Add-ons Bindings
61

Adding the logger definition in the log4j2.xml file and restart should be enough. Do you see any other ThingStatusInfoChangedEvent log entries?

What I meant was to review the implemention of the binding so that a connection retry won’t result in thing status updates.

62

Thank you!

63

Yes, that would be great!

64

Hi All,

Hopefully the right place to ask this (and I can’t find it already in the community) - I have a really old bravia - Model KDL-46EX650 - Until upgrading OH to 3.2 i’ve never bothered to try and connect it to OH - but now is the time.

There used to be an App on iOS to control the TV, but it disappeared ages ago. So I know it is possible to make this work. (Likely it used IRCC)

Firstly, Simple IP doesn’t work - Comms error - Looks like the port isn’t even open on the TV. So onto IRCC - from my reading the endpoint is /IRCC, when I put that in I get “Error registering renewal: 503 - No registration URL” so there is something there.

Any ideas how to get past this error?

Thanks for your help.

65

Never mind!!

Found the part about adding

discovery.sony-ircc:background=true

to runtime.cfg - and wow it worked.

As always RTFM! :rofl:

66

Hi!, I’ve tried again to enable the bindig and added
<Logger level="ERROR" name="org.apache.cxf.phase.PhaseInterceptorChain"/>
to the log4j2.xml but I still get the following erroros in every 10 sec. I’m sure I do something wrong, but I don’t know what. Does anybody have a clue what is wrong?

2022-02-23 23:12:59.709 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sony:simpleip:tv' changed from OFFLINE (COMMUNICATION_ERROR): Error connecting to device: not reachable to UNKNOWN: Initializing ...

2022-02-23 23:13:00.211 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sony:simpleip:tv' changed from UNKNOWN: Initializing ... to OFFLINE (COMMUNICATION_ERROR): Error connecting to device: not reachable

2022-02-23 23:13:10.211 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sony:simpleip:tv' changed from OFFLINE (COMMUNICATION_ERROR): Error connecting to device: not reachable to UNKNOWN: Initializing ...

2022-02-23 23:13:10.713 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing 'sony:simpleip:tv' changed from UNKNOWN: Initializing ... to OFFLINE (COMMUNICATION_ERROR): Error connecting to device: not reachable
67

I have a Sony BDP-S3700 Blu-Ray Player which is connected via Wi-Fi. I have the device configured for remote control and I set the device MAC Address in the thing configuration. IRCC power off works fine but when I power on the device receives the WOL packet and turns on but is then turned off immediately. If I turn it on then turn it off and back on in rapid succession I can get it to stay on.

Logs from turning the Blu-Ray off:

2022-02-25 14:59:53.964 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘MovieBluRay_IRCCPower’ received command ON
2022-02-25 14:59:53.969 [INFO ] [penhab.event.ItemStatePredictedEvent] - Item ‘MovieBluRay_IRCCPower’ predicted to become ON
2022-02-25 14:59:53.974 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from OFF to ON
2022-02-25 15:05:35.648 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘MovieBluRay_IRCCPower’ received command OFF
2022-02-25 15:05:35.652 [INFO ] [penhab.event.ItemStatePredictedEvent] - Item ‘MovieBluRay_IRCCPower’ predicted to become OFF
2022-02-25 15:05:35.657 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from ON to OFF
2022-02-25 15:05:38.390 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from OFF to ON
2022-02-25 15:06:06.519 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘sony:ircc:30d16b4c6d50’ changed from ONLINE to OFFLINE (COMMUNICATION_ERROR): Could not connect to 192.168.0.167:50001

Logs from turning Blu-Ray on:

2022-02-25 15:14:16.409 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘MovieBluRay_IRCCPower’ received command ON
2022-02-25 15:14:16.420 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from OFF to ON
2022-02-25 15:14:18.428 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘sony:ircc:30d16b4c6d50’ changed from OFFLINE (COMMUNICATION_ERROR): Could not connect to 192.168.0.167:50001 to UNKNOWN: Initializing …
==> /var/log/openhab/openhab.log <==
2022-02-25 15:14:19.909 [INFO ] [nding.sony.internal.ircc.IrccHandler] - WOL packet sent to (MACADDRESS removed)
2022-02-25 15:14:19.910 [INFO ] [g.sony.internal.AbstractThingHandler] - Received power on command when thing is offline - trying to turn on thing via WOL
==> /var/log/openhab/events.log <==
2022-02-25 15:14:18.930 [INFO ] [ab.event.ThingStatusInfoChangedEvent] - Thing ‘sony:ircc:30d16b4c6d50’ changed from UNKNOWN: Initializing … to OFFLINE (COMMUNICATION_ERROR): Error connecting to device: not reachable
2022-02-25 15:14:19.204 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘MovieBluRay_IRCCPower’ received command OFF
2022-02-25 15:14:19.211 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from ON to OFF
2022-02-25 15:14:19.896 [INFO ] [openhab.event.ItemCommandEvent ] - Item ‘MovieBluRay_IRCCPower’ received command ON
2022-02-25 15:14:19.904 [INFO ] [openhab.event.ItemStateChangedEvent ] - Item ‘MovieBluRay_IRCCPower’ changed from OFF to ON

68

Hi.

This is not an error but just an info of the thing status changes that happen when the binding is trying to re-connect to an offline device. As already mentioned in another post, I will try to change the implementation in the next binding release so that a retry won’t result in a thing status update (and thus no info messages).

69

I guess I’ve found the cause of your issue. The power command is executed twice because of command caching which happens when the device is not online. I will fix this issue asap.

1 Like
70

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:

image
image736×45 3.07 KB

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&registrationType=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?

71

Hi Oliver,

I would guess that your problem is caused by a bug that I might have introduced in course of various changes in the way the authentication or registration is performed. I will do some further analysis to identify and eventually fix the root cause, hopefully with your support as I don’t have any testing capabilities.

72

Hi Andreas,

thanks for your quick reply! Sure, let me know how I can support you with the troubleshooting and testing.

In the meantime I have found the following workaround to use the binding with Sony devices like STR-DN1030 which have implemented the “FULL” IRCC service also for the registration process (“mode 1” device for registration):

Manually registering Openhab Sony binding at AVR:

  1. Put the Sony into pairing mode
Settings/Network/Media Remote Device/Start Registration
  1. Manually perform registration process via browser
http://192.168.205.104:8080/description.xml
http://192.168.205.104:50001/cers/ActionList.xml
http://192.168.205.104:50001/cers/getSystemInformation
http://192.168.205.104:50001/cers/register?name=openHAB%20Sony&registrationType=initial&deviceId=MediaRemote%3A00-11-22-33-44-55

Afterwards a Media Remote with the name “openHAB Sony” and the deviceId “MediaRemote%3A00-11-22-33-44-55” is successfully registered with the Sony and I’m able to send IRCC commands.
It looks like Tim has hard coded the MAC address of 00-11-22-33-44-55 into the binding

73

I’ve managed to resume the work on your issue :slight_smile:

The binding code for authentication and registration is quite complex. It tries to find the proper type of authentication and registration in a generic way by posting various requests and evaluating the HTTP responses. This makes the analysis of the root cause somehow difficult.

For that reason it would be great if you could run a test version of the binding I’ve provided on my GitHub account:

https://github.com/andan67/openhab-addons/releases/tag/openhab-addons-sony-3.2.0-1-test

This version produces additional debug logs during the login process that includes the authentication check and possible registration.

The relevant log outputs should look similar to the following snippet:

2022-03-29 20:40:15.204 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 192.168.178.53, baseUri: /Ircc.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1332/0x00000007c0f06440@25502490 with initial authorization cookie: auth="";Version=1
2022-03-29 20:40:15.205 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Starting ircc login...
2022-03-29 20:40:15.206 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - accessCode = 'RQST'
2022-03-29 20:40:15.249 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Response status of calling non existing command: '200'
2022-03-29 20:40:15.382 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Response status of calling ircc status: '200'
2022-03-29 20:40:15.382 [DEBUG] [inding.sony.internal.SonyAuthChecker] - res: 'ok'
2022-03-29 20:40:15.383 [DEBUG] [inding.sony.internal.SonyAuthChecker] - checkResult: 'okCookie'
2022-03-29 20:40:15.383 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - checkResult: 'okCookie'
2022-03-29 20:40:15.383 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - setup cookie auth

Note that you likely will have to unregister the binding first from your device to get the real ‘initial’ (unregistered) situation.

74

Hi Andreas,

thanks a lot for your support and sorry for my late reply, but I needed to migrate the receiver from WiFi to LAN first before running the tests.
I have installed your test version of the binding and manually deleted the previous openhab mediaremote device registration from the Sony.

However it seems like the binding is not automatically discovering the device. Therefore I have manually added the thing using the IRCC URL http://10.0.10.84:8080/description.xml

please find below the output when saving the manually created thing file:

2022-04-10 13:20:57.968 [DEBUG] [g.sony.internal.AbstractThingHandler] - Thing initialization is called, trying to connect...
2022-04-10 13:20:57.975 [DEBUG] [nding.sony.internal.ircc.IrccHandler] - Attempting connection to IRCC device...
2022-04-10 13:20:57.979 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 10.0.10.84, baseUri: /description.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1938/0x56448228@129ee61 with initial authorization cookie: auth="";Version=1
2022-04-10 13:20:57.983 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml
2022-04-10 13:20:58.041 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and got IRCCRoot response: <?xml version="1.0"?>
<root
  xmlns="urn:schemas-upnp-org:device-1-0">
	<specVersion>
		<major>1</major>
		<minor>0</minor>
	</specVersion>
	<device>
		<av:X_UNR_DeviceInfo xmlns:av="urn:schemas-sony-com:av"><av:X_UNR_Version>1.2</av:X_UNR_Version><av:X_CERS_ActionList_URL>http://10.0.10.84:50001/cers/ActionList.xml</av:X_CERS_ActionList_URL><av:X_Remote_Type>RM-AAP078</av:X_Remote_Type><av:X_Remote_Type>RM-AAP079</av:X_Remote_Type></av:X_UNR_DeviceInfo>
		<dlna:X_DLNACAP xmlns:dlna="urn:schemas-dlna-org:device-1-0">playcontainer-0-1</dlna:X_DLNACAP>
		<av:X_StandardDMR xmlns:av="urn:schemas-sony-com:av">1.1</av:X_StandardDMR>
		<dlna:X_DLNADOC xmlns:dlna="urn:schemas-dlna-org:device-1-0">DMR-1.50</dlna:X_DLNADOC>
		<av:X_IRCC_DeviceInfo xmlns:av="urn:schemas-sony-com:av"><av:X_IRCC_Version>1.0</av:X_IRCC_Version><av:X_IRCC_CategoryList><av:X_IRCC_Category><av:X_CategoryInfo>AAMAAAEQ</av:X_CategoryInfo></av:X_IRCC_Category></av:X_IRCC_CategoryList></av:X_IRCC_DeviceInfo>
		<deviceType>urn:schemas-upnp-org:device:MediaRenderer:1</deviceType>
		<friendlyName>STR-DN1030 F73098</friendlyName>
		<manufacturer>Sony Corporation</manufacturer>
		<manufacturerURL>http://www.sony.net/</manufacturerURL>
		<modelDescription>MULTI CHANNEL AV RECEIVER</modelDescription>
		<modelName>STR-DN1030</modelName>
		<UDN>uuid:5f9ec1b3-ed59-1900-4530-5453edf73098</UDN>
		<iconList>
			<icon>
				<mimetype>image/jpeg</mimetype>
				<width>48</width>
				<height>48</height>
				<depth>24</depth>
				<url>/device_icon_48.jpg</url>
			</icon>
			<icon>
				<mimetype>image/jpeg</mimetype>
				<width>120</width>
				<height>120</height>
				<depth>24</depth>
				<url>/device_icon_120.jpg</url>
			</icon>
			<icon>
				<mimetype>image/png</mimetype>
				<width>48</width>
				<height>48</height>
				<depth>24</depth>
				<url>/device_icon_48.png</url>
			</icon>
			<icon>
				<mimetype>image/png</mimetype>
				<width>120</width>
				<height>120</height>
				<depth>24</depth>
				<url>/device_icon_120.png</url>
			</icon>
		</iconList>
		<serviceList>
			<service>
				<serviceType>urn:schemas-upnp-org:service:RenderingControl:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:RenderingControl</serviceId>
				<SCPDURL>/RenderingControl/desc.xml</SCPDURL>
				<controlURL>/RenderingControl/ctrl</controlURL>
				<eventSubURL>/RenderingControl/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-upnp-org:service:ConnectionManager:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:ConnectionManager</serviceId>
				<SCPDURL>/ConnectionManager/desc.xml</SCPDURL>
				<controlURL>/ConnectionManager/ctrl</controlURL>
				<eventSubURL>/ConnectionManager/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-upnp-org:service:AVTransport:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:AVTransport</serviceId>
				<SCPDURL>/AVTransport/desc.xml</SCPDURL>
				<controlURL>/AVTransport/ctrl</controlURL>
				<eventSubURL>/AVTransport/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-sony-com:service:Party:1</serviceType>
				<serviceId>urn:schemas-sony-com:serviceId:Party</serviceId>
				<SCPDURL>/Party_scpd.xml</SCPDURL>
				<controlURL>/Party_Control</controlURL>
				<eventSubURL>/Party_Event</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-sony-com:service:IRCC:1</serviceType>
				<serviceId>urn:schemas-sony-com:serviceId:IRCC</serviceId>
				<SCPDURL>/IRCCSCPD.xml</SCPDURL>
				<controlURL>/upnp/control/IRCC</controlURL>
				<eventSubURL>/</eventSubURL>
			</service>
		</serviceList>
	</device>
</root>
2022-04-10 13:20:58.042 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:RenderingControl -- org.openhab.binding.sony.internal.upnp.models.UpnpService@102fc92
2022-04-10 13:20:58.044 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and getting SCPD: http://10.0.10.84:8080/RenderingControl/desc.xml
2022-04-10 13:20:58.085 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and adding SCPD: http://10.0.10.84:8080/RenderingControl/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@94f060
2022-04-10 13:20:58.086 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:ConnectionManager -- org.openhab.binding.sony.internal.upnp.models.UpnpService@6d3a3
2022-04-10 13:20:58.087 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and getting SCPD: http://10.0.10.84:8080/ConnectionManager/desc.xml
2022-04-10 13:20:58.124 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and adding SCPD: http://10.0.10.84:8080/ConnectionManager/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@1758c88
2022-04-10 13:20:58.126 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:AVTransport -- org.openhab.binding.sony.internal.upnp.models.UpnpService@b4f5d9
2022-04-10 13:20:58.127 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and getting SCPD: http://10.0.10.84:8080/AVTransport/desc.xml
2022-04-10 13:20:58.180 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and adding SCPD: http://10.0.10.84:8080/AVTransport/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@62b4fe
2022-04-10 13:20:58.181 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:schemas-sony-com:serviceId:Party -- org.openhab.binding.sony.internal.upnp.models.UpnpService@313ebb
2022-04-10 13:20:58.183 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and getting SCPD: http://10.0.10.84:8080/Party_scpd.xml
2022-04-10 13:20:58.222 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and adding SCPD: http://10.0.10.84:8080/Party_scpd.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@1ab2323
2022-04-10 13:20:58.223 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:schemas-sony-com:serviceId:IRCC -- org.openhab.binding.sony.internal.upnp.models.UpnpService@394a3
2022-04-10 13:20:58.224 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and getting SCPD: http://10.0.10.84:8080/IRCCSCPD.xml
2022-04-10 13:20:58.263 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and adding SCPD: http://10.0.10.84:8080/IRCCSCPD.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@120eaee
2022-04-10 13:20:58.265 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and finding action: http://10.0.10.84:50001/cers/ActionList.xml
2022-04-10 13:20:58.594 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found action: http://10.0.10.84:50001/cers/ActionList.xml -- org.openhab.binding.sony.internal.ircc.models.IrccActionList@1de565
2022-04-10 13:20:58.610 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and getting system information: http://10.0.10.84:50001/cers/getSystemInformation
2022-04-10 13:20:59.354 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found system information: http://10.0.10.84:50001/cers/getSystemInformation -- org.openhab.binding.sony.internal.ircc.models.IrccSystemInformation@148e4ae
2022-04-10 13:20:59.356 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and getting remote commands: http://10.0.10.84:50001/cers/getRemoteCommandList
2022-04-10 13:20:59.432 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and encountered an error getting remote commands (using default now): 403 (http reason: Forbidden)
2022-04-10 13:20:59.435 [DEBUG] [nternal.transports.SonyHttpTransport] - Closing http client
2022-04-10 13:20:59.439 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 10.0.10.84, baseUri: /description.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1938/0x56448228@145abda with initial authorization cookie: auth="";Version=1
2022-04-10 13:20:59.442 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Starting ircc login...
2022-04-10 13:20:59.444 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - accessCode = 'RQST'
2022-04-10 13:20:59.541 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Response status of calling non existing command: '500'
2022-04-10 13:20:59.542 [DEBUG] [inding.sony.internal.SonyAuthChecker] - res: 'ok'
2022-04-10 13:20:59.543 [DEBUG] [inding.sony.internal.SonyAuthChecker] - checkResult: 'okCookie'
2022-04-10 13:20:59.545 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - checkResult: 'okCookie'
2022-04-10 13:20:59.546 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - setup cookie auth
2022-04-10 13:20:59.548 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - No command map defined - ignoring
2022-04-10 13:20:59.550 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-04-10 13:20:59.553 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-04-10 13:20:59.555 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-04-10 13:20:59.558 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-04-10 13:20:59.561 [DEBUG] [nding.sony.internal.ircc.IrccHandler] - IRCC System now connected

hope this provides you the required debug logging information? I will keep the testing setup for the time being. Please let me know if you need any further logs.

thanks a lot!

75

Thank you for installing the test version and providing the helpful logs.
Some observations/questions:

  • Can you confirm that the binding has been un-registered before? If yes, then I would expect to see a Forbidden error in the logs few lines after ‘IRCC System now connected’
  • The binding checks the authentication/registration status by evaluating the HTPP response status received when a non existing command is posted. In your case the code is 500, which lets the binding assume that authentication is available (i.e. no registration required).

It seems as if the evaluation logic for the detection of the registration status is not applicable for your device. I haven’t touched this part of the code, so this behaviour should also be observable in the original binding versions for OH 2.x (at least the late versions).

So if the first point is confirmed, than I should have all information to start working on a fix.

76

not sure what exactly you mean with “binding has been un-registered before”?

Here is what I did so far:

  1. uninstalled the previous Sony binding via UI
  2. deleted the openhab MediaRemote registration on the Sony STR-DN1030 Receiver
  3. installed your test binding via Karaf console
  4. deleted the existing Sony Receiver Thing in openhab
  5. as no autodiscovery was adding the Sony to the inbox, I manually added the Sony using the IRCC URL
  6. Put the Sony in registration mode
  7. Initiated registration in the binding by editing access code “RQST” and saving thing file. (tried this with “RQST” and “” as well)

btw. after the “IRCC System now connected” message the binding is checking the connectivity every 60seconds but gets 403

2022-04-10 13:22:26.713 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 10.0.10.84:8080 - successful
2022-04-10 13:22:26.731 [DEBUG] [y.internal.transports.SonyAuthFilter] - Trying to renew our authorization cookie for host: 10.0.10.84
2022-04-10 13:22:26.764 [DEBUG] [y.internal.transports.SonyAuthFilter] - No authorization cookie was returned
2022-04-10 13:22:26.826 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Unknown code from getStatus: 403 (http reason: Forbidden)
2022-04-10 13:22:56.741 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 10.0.10.84:8080 - successful
2022-04-10 13:22:56.841 [DEBUG] [y.internal.transports.SonyAuthFilter] - Trying to renew our authorization cookie for host: 10.0.10.84
2022-04-10 13:22:56.876 [DEBUG] [y.internal.transports.SonyAuthFilter] - No authorization cookie was returned
2022-04-10 13:22:56.939 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Unknown code from getStatus: 403 (http reason: Forbidden)

please let me know if I missed something?

77

Your step 2) was what I meant with un-registering the binding (as control of the Sony device).

Your information confirms my assumption about the root cause. I will provide a fixed version for testing within the next few days,

78

I’ve tried to fix your issue in a test release:
openhab-addons-sony-3.2.0-1-test

If this fix works for you, I will merge it into the next release version.

Thanks

1 Like
80

Hello together,

first i want to say that the binding is sooooo good :partying_face:
Before i encountered this i controled my tv by infrared with a rasperry pi zero over mqtt. But with this actions are so limited.

I now try to fight through the jungle of options and feel a bit lost. (scalar thing)

At the moment i have 2 questions im stuck on:

  1. Can i get information of whats actual playing inside apps like youtube or netflix? The data channels of these are always empty.
  2. Can i stream any content on any app like youtube?
81

Hi Andreas,

great work! I have installed your new test release and the binding successfully registered openhab as MediaRemote device openHAB (MediaRemote:00-11-22-33-00:11:22:33:44:55) on the Sony AVR!

2022-04-26 17:58:34.509 [DEBUG] [g.sony.internal.AbstractThingHandler] - Thing initialization is called, trying to connect...
2022-04-26 17:58:34.515 [DEBUG] [nding.sony.internal.ircc.IrccHandler] - Attempting connection to IRCC device...
2022-04-26 17:58:34.562 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 10.0.10.84, baseUri: /description.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1962/0x5677d628@dd3e52 with initial authorization cookie: auth="";Version=1
2022-04-26 17:58:34.567 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml
2022-04-26 17:58:34.741 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and got IRCCRoot response: <?xml version="1.0"?>
<root
  xmlns="urn:schemas-upnp-org:device-1-0">
	<specVersion>
		<major>1</major>
		<minor>0</minor>
	</specVersion>
	<device>
		<av:X_UNR_DeviceInfo xmlns:av="urn:schemas-sony-com:av"><av:X_UNR_Version>1.2</av:X_UNR_Version><av:X_CERS_ActionList_URL>http://10.0.10.84:50001/cers/ActionList.xml</av:X_CERS_ActionList_URL><av:X_Remote_Type>RM-AAP078</av:X_Remote_Type><av:X_Remote_Type>RM-AAP079</av:X_Remote_Type></av:X_UNR_DeviceInfo>
		<dlna:X_DLNACAP xmlns:dlna="urn:schemas-dlna-org:device-1-0">playcontainer-0-1</dlna:X_DLNACAP>
		<av:X_StandardDMR xmlns:av="urn:schemas-sony-com:av">1.1</av:X_StandardDMR>
		<dlna:X_DLNADOC xmlns:dlna="urn:schemas-dlna-org:device-1-0">DMR-1.50</dlna:X_DLNADOC>
		<av:X_IRCC_DeviceInfo xmlns:av="urn:schemas-sony-com:av"><av:X_IRCC_Version>1.0</av:X_IRCC_Version><av:X_IRCC_CategoryList><av:X_IRCC_Category><av:X_CategoryInfo>AAMAAAEQ</av:X_CategoryInfo></av:X_IRCC_Category></av:X_IRCC_CategoryList></av:X_IRCC_DeviceInfo>
		<deviceType>urn:schemas-upnp-org:device:MediaRenderer:1</deviceType>
		<friendlyName>STR-DN1030 F73098</friendlyName>
		<manufacturer>Sony Corporation</manufacturer>
		<manufacturerURL>http://www.sony.net/</manufacturerURL>
		<modelDescription>MULTI CHANNEL AV RECEIVER</modelDescription>
		<modelName>STR-DN1030</modelName>
		<UDN>uuid:5f9ec1b3-ed59-1900-4530-5453edf73098</UDN>
		<iconList>
			<icon>
				<mimetype>image/jpeg</mimetype>
				<width>48</width>
				<height>48</height>
				<depth>24</depth>
				<url>/device_icon_48.jpg</url>
			</icon>
			<icon>
				<mimetype>image/jpeg</mimetype>
				<width>120</width>
				<height>120</height>
				<depth>24</depth>
				<url>/device_icon_120.jpg</url>
			</icon>
			<icon>
				<mimetype>image/png</mimetype>
				<width>48</width>
				<height>48</height>
				<depth>24</depth>
				<url>/device_icon_48.png</url>
			</icon>
			<icon>
				<mimetype>image/png</mimetype>
				<width>120</width>
				<height>120</height>
				<depth>24</depth>
				<url>/device_icon_120.png</url>
			</icon>
		</iconList>
		<serviceList>
			<service>
				<serviceType>urn:schemas-upnp-org:service:RenderingControl:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:RenderingControl</serviceId>
				<SCPDURL>/RenderingControl/desc.xml</SCPDURL>
				<controlURL>/RenderingControl/ctrl</controlURL>
				<eventSubURL>/RenderingControl/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-upnp-org:service:ConnectionManager:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:ConnectionManager</serviceId>
				<SCPDURL>/ConnectionManager/desc.xml</SCPDURL>
				<controlURL>/ConnectionManager/ctrl</controlURL>
				<eventSubURL>/ConnectionManager/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-upnp-org:service:AVTransport:1</serviceType>
				<serviceId>urn:upnp-org:serviceId:AVTransport</serviceId>
				<SCPDURL>/AVTransport/desc.xml</SCPDURL>
				<controlURL>/AVTransport/ctrl</controlURL>
				<eventSubURL>/AVTransport/evt</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-sony-com:service:Party:1</serviceType>
				<serviceId>urn:schemas-sony-com:serviceId:Party</serviceId>
				<SCPDURL>/Party_scpd.xml</SCPDURL>
				<controlURL>/Party_Control</controlURL>
				<eventSubURL>/Party_Event</eventSubURL>
			</service>
			<service>
				<serviceType>urn:schemas-sony-com:service:IRCC:1</serviceType>
				<serviceId>urn:schemas-sony-com:serviceId:IRCC</serviceId>
				<SCPDURL>/IRCCSCPD.xml</SCPDURL>
				<controlURL>/upnp/control/IRCC</controlURL>
				<eventSubURL>/</eventSubURL>
			</service>
		</serviceList>
	</device>
</root>
2022-04-26 17:58:34.743 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:RenderingControl -- org.openhab.binding.sony.internal.upnp.models.UpnpService@22ca1d
2022-04-26 17:58:34.744 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and getting SCPD: http://10.0.10.84:8080/RenderingControl/desc.xml
2022-04-26 17:58:34.835 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:RenderingControl and adding SCPD: http://10.0.10.84:8080/RenderingControl/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@c5323f
2022-04-26 17:58:34.836 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:ConnectionManager -- org.openhab.binding.sony.internal.upnp.models.UpnpService@e1f952
2022-04-26 17:58:34.836 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and getting SCPD: http://10.0.10.84:8080/ConnectionManager/desc.xml
2022-04-26 17:58:34.872 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:ConnectionManager and adding SCPD: http://10.0.10.84:8080/ConnectionManager/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@6b4a34
2022-04-26 17:58:34.873 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:upnp-org:serviceId:AVTransport -- org.openhab.binding.sony.internal.upnp.models.UpnpService@1f0e830
2022-04-26 17:58:34.873 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and getting SCPD: http://10.0.10.84:8080/AVTransport/desc.xml
2022-04-26 17:58:34.927 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:upnp-org:serviceId:AVTransport and adding SCPD: http://10.0.10.84:8080/AVTransport/desc.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@7c6ba5
2022-04-26 17:58:34.927 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:schemas-sony-com:serviceId:Party -- org.openhab.binding.sony.internal.upnp.models.UpnpService@bf03ba
2022-04-26 17:58:34.928 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and getting SCPD: http://10.0.10.84:8080/Party_scpd.xml
2022-04-26 17:58:34.966 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:Party and adding SCPD: http://10.0.10.84:8080/Party_scpd.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@11c892b
2022-04-26 17:58:34.967 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found service: urn:schemas-sony-com:serviceId:IRCC -- org.openhab.binding.sony.internal.upnp.models.UpnpService@1e2f2fd
2022-04-26 17:58:34.968 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and getting SCPD: http://10.0.10.84:8080/IRCCSCPD.xml
2022-04-26 17:58:35.001 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml -- urn:schemas-sony-com:serviceId:IRCC and adding SCPD: http://10.0.10.84:8080/IRCCSCPD.xml -- org.openhab.binding.sony.internal.upnp.models.UpnpScpd@5f0f25
2022-04-26 17:58:35.002 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and finding action: http://10.0.10.84:50001/cers/ActionList.xml
2022-04-26 17:58:35.339 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found action: http://10.0.10.84:50001/cers/ActionList.xml -- org.openhab.binding.sony.internal.ircc.models.IrccActionList@1268488
2022-04-26 17:58:35.340 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and getting system information: http://10.0.10.84:50001/cers/getSystemInformation
2022-04-26 17:58:36.086 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and found system information: http://10.0.10.84:50001/cers/getSystemInformation -- org.openhab.binding.sony.internal.ircc.models.IrccSystemInformation@28f266
2022-04-26 17:58:36.087 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and getting remote commands: http://10.0.10.84:50001/cers/getRemoteCommandList
2022-04-26 17:58:36.160 [DEBUG] [sony.internal.ircc.IrccClientFactory] - Querying IRCC client http://10.0.10.84:8080/description.xml and encountered an error getting remote commands (using default now): 403 (http reason: Forbidden)
2022-04-26 17:58:36.165 [DEBUG] [nternal.transports.SonyHttpTransport] - Closing http client
2022-04-26 17:58:36.168 [DEBUG] [y.internal.transports.SonyAuthFilter] - Created SonyAuthFilter for host: 10.0.10.84, baseUri: /description.xml, autoAuth: org.openhab.binding.sony.internal.transports.SonyHttpTransport$$Lambda$1962/0x5677d628@a894b4 with initial authorization cookie: auth="";Version=1
2022-04-26 17:58:36.174 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Starting ircc login...
2022-04-26 17:58:36.175 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - accessCode = 'RQST'
2022-04-26 17:58:36.262 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Response status of calling non existing command: '500'
2022-04-26 17:58:36.336 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - Response status of calling ircc status: '403'
2022-04-26 17:58:36.338 [DEBUG] [inding.sony.internal.SonyAuthChecker] - res: 'needspairing'
2022-04-26 17:58:36.340 [DEBUG] [inding.sony.internal.SonyAuthChecker] - checkResult: 'needspairing'
2022-04-26 17:58:36.341 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - checkResult: 'needspairing'
2022-04-26 17:58:36.342 [DEBUG] [enhab.binding.sony.internal.SonyAuth] - Requesting access: (initial)
2022-04-26 17:58:36.483 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - No command map defined - ignoring
2022-04-26 17:58:36.485 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-04-26 17:58:36.489 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-04-26 17:58:36.491 [DEBUG] [g.sony.internal.AbstractThingHandler] - Starting state polling every 30 seconds
2022-04-26 17:58:36.493 [DEBUG] [g.sony.internal.AbstractThingHandler] - Executing any cached commands
2022-04-26 17:58:36.493 [DEBUG] [nding.sony.internal.ircc.IrccHandler] - IRCC System now connected
2022-04-26 17:59:06.496 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 10.0.10.84:8080 - successful
2022-04-26 17:59:06.525 [DEBUG] [y.internal.transports.SonyAuthFilter] - Trying to renew our authorization cookie for host: 10.0.10.84
2022-04-26 17:59:06.583 [DEBUG] [y.internal.transports.SonyAuthFilter] - No authorization cookie was returned
2022-04-26 17:59:07.174 [DEBUG] [ding.sony.internal.ircc.IrccProtocol] - getContentInformation is not implemented

one little remark: It seems that the binding is not discovering the remote commands from the Sony and therefore not automatically creating the corresponding .map file.
As I still have a map file from the previous binding which I manually configured in the thing properties and afterwards everything is working fine again and I can control the Sony STR-DN1030 again. But maybe this would be useful for other users?

Thanks a lot for your support and great work on this binding!