Sony Devices Binding

tmrobert8 · March 5, 2019, 6:40pm

@suntribe
I’m actually taking a slightly different approach. There will be two directories that the addon will use:

USERDATA/sony/db - this will be the directory to read device definitions from
USERDATA/sony/tmpdb - this will be the directory that devices are written to

So on startup …
The binding will read any json files in the sony/db directory and create sony specific thing types from them. My goal is to create a set of files from everyone that will encapsulate most of the sony devices and what channels are valid for them. You could then additionally put your own custom device file if you further want to customize it (such as USB storage stuff that would be specific to you).

On device going online - the binding will WRITE the device (and the non-dynamic channels) to the tmpdb. This is mainly so you can modify it as needed (to copy to the sony/db directory) and/or to send to me to be included in the global one’s that will be distributed.

The wrinkle to all this is what I’m calling dynamic channels. A dynamic channel is something that is specific to the device (not generic to all similar devices). Example would USB storage or applications - those would still be generated on the fly by the binding.

nick_woodforth · March 6, 2019, 12:59pm

hey Tim,

tried the latest version. on 1 of my tvs it looks to be working currently. on anther tv it looks to be looping every 30secs. i have copied the debug log to a doc for now as its going to be way to long to put in here.
its about 145 pages long for 23 seconds worthlogs.pdf (730.2 KB)

Knut1507 · March 6, 2019, 8:24pm

With the newest Binding the auto-discovery of Things worked good. Things for Scalar, Dial and IRCC appeared in my inbox. The SimpleIP Thing did not show up but i was able to add it manually.
I relinked my Items to the new SimpleIP Thing Channels. Everything worked as before. I have still some small issues with the SimpleIP Thing.

My TV is KD-49X8505B. PowerSaving is turned off and WoL ist active. When I turn the tv off after 5 minutes the services for the binding are going offline. So i cant use the Power Channel to turn it on again. I dont know if there is some WoL implemented in the binding but if it doesnt work.
I use a separate WoL binding to start my tv now. I use a Rule to sync my WoL Switch with the Power Channel to get the real state.

I can send number via setpoint to the sony:simpleip:xxxxxxxx:channel and switch channels. But if it switch to radio channels following happens:

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:11:11.744 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_IRCode’ received command Tv_Radio

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:11:11.746 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Tv_Radio with map file ‘sonysimpleip.map’ to 51

2019-03-06 21:11:11.747 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCIRCC0000000000000051’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:11:11.747 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Tv_Radio

==> /openhab/userdata/logs/openhab.log <==

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:11:11.759 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from NULL to Tv_Radio

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:11:11.785 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCIRCC0000000000000051’ result: ‘*SAIRCC0000000000000000’

2019-03-06 21:11:11.786 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPOWR################’

2019-03-06 21:11:12.892 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPOWR################’ result: ‘*SAPOWR0000000000000001’

2019-03-06 21:11:12.893 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’

2019-03-06 21:11:13.158 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’

2019-03-06 21:11:13.159 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:11:13.301 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000643.0000000’

2019-03-06 21:11:13.302 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:11:13.306 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 3.0 to 643.0

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:11:13.400 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F18####’

2019-03-06 21:11:13.401 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEISRC################’

2019-03-06 21:11:13.515 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEISRC################’ result: ‘*SAISRCdvbc############’

2019-03-06 21:11:13.516 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:11:13.725 [DEBUG] [ny.internal.net.SocketChannelSession] - Dispatching response: *SNCHNN00000643.0000000

2019-03-06 21:11:13.798 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’

*SACHNN00000643.0000000’ to command ‘*SECHNN################’

2019-03-06 21:11:16.802 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’

’ to command ‘*SECHNN################’

So some Warnings appear. While in the Radio Channels I cant use the Setpoint anymore. Channel UP / Channel Down still work.

2019-03-06 21:18:24.733 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_IRCode’ received command Channel-Up

2019-03-06 21:18:24.736 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Channel-Up

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:24.734 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Channel-Up with map file ‘sonysimpleip.map’ to 33

2019-03-06 21:18:24.738 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCIRCC0000000000000033’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:18:24.756 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from Tv_Radio to Channel-Up

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:24.777 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCIRCC0000000000000033’ result: ‘*SAIRCC0000000000000000’

2019-03-06 21:18:24.778 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPOWR################’

2019-03-06 21:18:25.022 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPOWR################’ result: ‘*SAPOWR0000000000000001’

2019-03-06 21:18:25.024 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’

2019-03-06 21:18:25.872 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’

2019-03-06 21:18:25.873 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:26.113 [DEBUG] [ny.internal.net.SocketChannelSession] - Dispatching response: *SNCHNN00000644.0000000

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:18:26.116 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 643.0 to 644.0

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:26.138 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’

*SACHNN00000644.0000000’ to command ‘*SECHNN################’

2019-03-06 21:18:29.142 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’

’ to command ‘*SECHNN################’

2019-03-06 21:18:29.143 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:29.252 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:29.254 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEISRC################’

2019-03-06 21:18:29.347 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEISRC################’ result: ‘*SAISRCdvbc############’

2019-03-06 21:18:29.348 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:29.446 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:18:29.450 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:29.543 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:29.544 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPIPI################’

2019-03-06 21:18:29.643 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPIPI################’ result: ‘*SAPIPI0000000000000000’

2019-03-06 21:18:29.644 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’

2019-03-06 21:18:29.742 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’

2019-03-06 21:18:29.744 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:29.853 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:18:29.856 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:30.680 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:30.682 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEISRC################’

2019-03-06 21:18:30.780 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEISRC################’ result: ‘*SAISRCdvbc############’

2019-03-06 21:18:30.781 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:30.878 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:18:30.879 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:31.057 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:31.058 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPIPI################’

2019-03-06 21:18:31.187 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPIPI################’ result: ‘*SAPIPI0000000000000000’

2019-03-06 21:18:42.137 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’

2019-03-06 21:18:42.230 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’

2019-03-06 21:18:42.235 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:42.326 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:18:42.328 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:42.423 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:42.424 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEISRC################’

2019-03-06 21:18:42.523 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEISRC################’ result: ‘*SAISRCdvbc############’

2019-03-06 21:18:42.524 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:18:42.619 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:18:42.620 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

2019-03-06 21:18:42.716 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F19####’

2019-03-06 21:18:42.716 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPIPI################’

2019-03-06 21:18:42.811 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPIPI################’ result: ‘*SAPIPI0000000000000000’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:18:44.205 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_Channel’ received command 643

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:44.208 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCCHNN00000643.0000000’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:18:44.208 [nt.ItemStatePredictedEvent] - SonySimpleIP_Channel predicted to become 643

2019-03-06 21:18:44.211 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 644.0 to 643

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:44.758 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCCHNN00000643.0000000’ result: ‘*SACHNNNNNNNNNNNNNNNNNN’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:18:44.761 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 643 to

==> /openhab/userdata/logs/openhab.log <==

2019-03-06 21:18:47.332 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 192.168.0.14:20060 - successful

2019-03-06 21:19:12.812 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’

2019-03-06 21:19:12.912 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’

2019-03-06 21:19:12.914 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’

2019-03-06 21:19:13.010 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’

2019-03-06 21:19:13.011 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’

==> /openhab/userdata/logs/events.log <==

2019-03-06 21:19:13.015 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from to 644.0

As you might see the channel is 644 and get command 643 and changes but then it changes to nothing and then from nothing back to 644. On the TV nothing changes while this happens.

IR Code Power dosent turn the TV off

2019-03-06 21:33:59.590 [ome.event.ItemCommandEvent] - Item 'SonySimpleIP_IRCode' received command Power

2019-03-06 21:33:59.594 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Power

==&gt; /openhab/userdata/logs/openhab.log &lt;==

2019-03-06 21:33:59.596 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Power with map file 'sonysimpleip.map' to 98

2019-03-06 21:33:59.597 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SCIRCC0000000000000098'

2019-03-06 21:33:59.603 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SCIRCC0000000000000098' result: '*SAIRCCFFFFFFFFFFFFFFFF'

2019-03-06 21:33:59.604 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - IRCC command failed: FFFFFFFFFFFFFFFF

2019-03-06 21:33:59.604 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPOWR################'

==&gt; /openhab/userdata/logs/events.log &lt;==

2019-03-06 21:33:59.609 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from Power-On to Power

==&gt; /openhab/userdata/logs/openhab.log &lt;==

2019-03-06 21:33:59.718 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPOWR################' result: '*SAPOWR0000000000000001'

2019-03-06 21:33:59.719 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'

2019-03-06 21:34:00.711 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'

2019-03-06 21:34:00.713 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:00.921 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:00.923 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:01.015 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:01.016 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'

2019-03-06 21:34:01.115 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCdvbc############'

2019-03-06 21:34:01.116 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:01.212 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:01.215 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:01.316 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:01.317 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'

2019-03-06 21:34:01.416 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPI0000000000000000'

2019-03-06 21:34:01.417 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'

2019-03-06 21:34:01.512 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'

2019-03-06 21:34:01.514 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:01.614 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:01.615 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:01.709 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:01.710 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'

2019-03-06 21:34:01.805 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCdvbc############'

2019-03-06 21:34:01.807 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:01.901 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:01.903 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:02.000 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:02.001 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'

2019-03-06 21:34:02.099 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPI0000000000000000'

2019-03-06 21:34:06.601 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'

2019-03-06 21:34:06.697 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'

2019-03-06 21:34:06.697 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:06.792 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:06.794 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:06.891 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:06.892 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'

2019-03-06 21:34:06.987 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCdvbc############'

2019-03-06 21:34:06.988 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:34:07.083 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000003.0000000'

2019-03-06 21:34:07.085 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:34:07.181 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNF001271FC35C####'

2019-03-06 21:34:07.182 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'

2019-03-06 21:34:07.279 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPI0000000000000000'

2019-03-06 21:34:17.389 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 192.168.0.14:20060 - successful

And Power-On doesn’t turn the TV on:

==&gt; /openhab/userdata/logs/events.log &lt;==

2019-03-06 21:37:40.408 [ome.event.ItemCommandEvent] - Item 'SonySimpleIP_IRCode' received command Power-On

==&gt; /openhab/userdata/logs/openhab.log &lt;==

2019-03-06 21:37:40.414 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Power-On with map file 'sonysimpleip.map' to 103

==&gt; /openhab/userdata/logs/events.log &lt;==

2019-03-06 21:37:40.418 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Power-On

==&gt; /openhab/userdata/logs/openhab.log &lt;==

2019-03-06 21:37:40.431 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SCIRCC0000000000000103'

==&gt; /openhab/userdata/logs/events.log &lt;==

2019-03-06 21:37:40.432 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from Power to Power-On

==&gt; /openhab/userdata/logs/openhab.log &lt;==

2019-03-06 21:37:40.448 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SCIRCC0000000000000103' result: '*SAIRCCFFFFFFFFFFFFFFFF'

2019-03-06 21:37:40.449 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - IRCC command failed: FFFFFFFFFFFFFFFF

2019-03-06 21:37:40.449 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPOWR################'

2019-03-06 21:37:40.627 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPOWR################' result: '*SAPOWR0000000000000000'

2019-03-06 21:37:40.628 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'

2019-03-06 21:37:40.721 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLUFFFFFFFFFFFFFFFF'

2019-03-06 21:37:40.723 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'

2019-03-06 21:37:40.819 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNNFFFFFFFFFFFFFFFF'

2019-03-06 21:37:40.821 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

2019-03-06 21:37:40.961 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHNFFFFFFFFFFFFFFFF'

2019-03-06 21:37:40.962 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'

2019-03-06 21:37:41.053 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCFFFFFFFFFFFFFFFF'

2019-03-06 21:37:41.054 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'

2019-03-06 21:37:41.151 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPIFFFFFFFFFFFFFFFF'

2019-03-06 21:37:41.153 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Error PIP channel

In my MAP file is hdmi1 twice:

Power=98
Hdmi1=100
Power-On=103
Sleep=104
Sleep-Timer=105
Composite1=107
Video2=108
Picture-Mode=110
Demo-Surround=121
Hdmi1=124
Hdmi2=125
Hdmi3=126
Hdmi4=127

I think the MAP file will never use the second one? I think the MAP is generated dynamically so this maybe is just what the tv gives to the Binding.

Dont get me wrong. This are not big issues or even an issue at all. Just wanted to share my experience. Maybe it helps to improve the binding maybe it is not worth a reply. I am ok with both. You did a great job on this binding already.

Simsal · March 6, 2019, 8:30pm

Hey,
thank you very much for your efforts and congratz on the rework.
So far everything ist working very nicely for me.

Just one question, is there a Channel which shows the current status of the tv? I don’t mean the power status, but if netflix is running, or tv, or amazon, or…

tmrobert8 · March 6, 2019, 9:29pm

@Knut1507

Good post - to the points:

SimpleIP doesn’t support WOL and it’s device dependent whether simpleip is listening when off. I’m guessing yours isn’t so using the WOL binding probably would work. I may add a config option to send a WOL with the power toggle for the next version however.
Warning msg - next version should do that properly (don’t know why that response had a newline in it - but I’m stripping those now).
644->643. Interesting - so you set 643 in the channel and openhab assume’s its set (the predicting part in the log). We sent 643 to the TV and it reported back that it was INVALID channel (not found). The binding set’s the channel to blank (probably should be UNDEF). The state update swings through and asks the TV for the current channel - it reports 644 and we set the channel back to 644. End result is what you see - TV doesn’t change and the channel reverts back to 644. As far as I can tell - that’s all valid and how it should work. Correct me if I’m wrong on that…
IRCC codes are device dependent btw and may not work. I’ve included every code that I have found but they may not be valid for every device. Power-On definitely is not valid for your device (it’s reporting back an error). You can look at what Sony published (https://pro-bravia.sony.net/develop/integrate/ssip/command-definitions/) but I can tell you they did not document everything (nor even document the API properly)…
Good catch on the hdmi1 thing - I’ll delete the 101 version since 124 is in their official documentation for the next version…

tmrobert8 · March 6, 2019, 9:31pm

@Simsal

Still working on that actually - the TVs use to report back what they were running but that doesn’t seem to happen anymore. I believe you can go into the config and enable the ‘refresh’ (by putting say 30 in it) and it should detect what’s running every 30 seconds - but do note this is very intensive as it has to query each application for it’s status (so if you have 50 apps - it will be sending 50 queries to your TV every 30 seconds). I’m still looking into a way to short cut that…

johfeu · March 6, 2019, 10:20pm

with the latest version i can connect to both DVDBR and TV but still not everything is working. One obvious thing is, the scalar thing does not initialize. It is stuck in state INITIALIZING. The only thing in the debug log which looks related is:

 2019-03-06 23:14:04.439 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: videoScreen#scenesetting accepting String of type scalarvideoscreenscenesetting
 2019-03-06 23:14:04.794 [WARN ] [mmon.WrappedScheduledExecutorService] - Scheduled runnable ended with an exception: 
 java.lang.IllegalArgumentException: Duplicate channels sony:scalar:fcf152119bfb:appControl#arte-apptitle
 	at org.eclipse.smarthome.core.thing.util.ThingHelper.ensureUniqueChannels(ThingHelper.java:159) ~[?:?]
 	at org.eclipse.smarthome.core.thing.util.ThingHelper.ensureUniqueChannels(ThingHelper.java:135) ~[?:?]
 	at org.eclipse.smarthome.core.thing.binding.builder.ThingBuilder.withChannels(ThingBuilder.java:85) ~[?:?]
 	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:208) ~[?:?]
 	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.lambda$0(ScalarWebHandler.java:162) ~[?:?]
 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) ~[?:?]
 	at java.util.concurrent.FutureTask.run(FutureTask.java:266) ~[?:?]
 	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180) ~[?:?]
 	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293) ~[?:?]
 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:?]
 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:?]
 	at java.lang.Thread.run(Thread.java:748) [?:?]

Knut1507 · March 7, 2019, 10:26am

It would be valid if the Channel 643 was indeed INVALID but it isnt. 643 and 644 are both valid radio channels. I can switch to them an listen to the radio.

The log starting with being on a TV channel 3. Then I use IRCode Tv_Radio to switch to the last used radio channel. In my case 643. This is happening here:

==> /openhab/userdata/logs/events.log <==
2019-03-06 21:11:11.744 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_IRCode’ received command Tv_Radio
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:11:11.746 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Tv_Radio with map file ‘sonysimpleip.map’ to 51
2019-03-06 21:11:11.747 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCIRCC0000000000000051’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:11:11.747 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Tv_Radio
==> /openhab/userdata/logs/openhab.log <==
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:11:11.759 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from NULL to Tv_Radio
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:11:11.785 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCIRCC0000000000000051’ result: ‘*SAIRCC0000000000000000’
2019-03-06 21:11:11.786 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPOWR################’
2019-03-06 21:11:12.892 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPOWR################’ result: ‘*SAPOWR0000000000000001’
2019-03-06 21:11:12.893 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’
2019-03-06 21:11:13.158 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’
2019-03-06 21:11:13.159 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’
2019-03-06 21:11:13.301 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000643.0000000’
2019-03-06 21:11:13.302 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:11:13.306 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 3.0 to 643.0
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:11:13.400 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SETCHN################’ result: ‘*SATCHN000104316F18####’
2019-03-06 21:11:13.401 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEISRC################’
2019-03-06 21:11:13.515 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEISRC################’ result: ‘*SAISRCdvbc############’
2019-03-06 21:11:13.516 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’
2019-03-06 21:11:13.725 [DEBUG] [ny.internal.net.SocketChannelSession] - Dispatching response: *SNCHNN00000643.0000000
2019-03-06 21:11:13.798 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’
*SACHNN00000643.0000000’ to command ‘*SECHNN################’
2019-03-06 21:11:16.802 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’
’ to command ‘*SECHNN################’

So I swtiched from channel 3 to 643. Radio ist playing now. I can use the IRCode Channel-Up to switch to the next radio channel. Like you see in the following log:

==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:24.733 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_IRCode’ received command Channel-Up
2019-03-06 21:18:24.736 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Channel-Up
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:24.734 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Channel-Up with map file ‘sonysimpleip.map’ to 33
2019-03-06 21:18:24.738 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCIRCC0000000000000033’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:24.756 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from Tv_Radio to Channel-Up
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:24.777 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCIRCC0000000000000033’ result: ‘*SAIRCC0000000000000000’
2019-03-06 21:18:24.778 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEPOWR################’
2019-03-06 21:18:25.022 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEPOWR################’ result: ‘*SAPOWR0000000000000001’
2019-03-06 21:18:25.024 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’
2019-03-06 21:18:25.872 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’
2019-03-06 21:18:25.873 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’
2019-03-06 21:18:26.113 [DEBUG] [ny.internal.net.SocketChannelSession] - Dispatching response: *SNCHNN00000644.0000000
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:26.116 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 643.0 to 644.0
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:26.138 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’
*SACHNN00000644.0000000’ to command ‘*SECHNN################’
2019-03-06 21:18:29.142 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response ’
’ to command ‘*SECHNN################’

Now i am on radio channel 644. Another radio station is now playing. So both channels are valid and i can switch between them via IRCode Channel-Up. Now i attempt to go back to 643 but not via IRCode Channel-Down. I try to set the channel 643 directly via SonySimpleIP_Channel 643. By doing this its acting like the channel is not valid.

==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:44.205 [ome.event.ItemCommandEvent] - Item ‘SonySimpleIP_Channel’ received command 643
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:44.208 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SCCHNN00000643.0000000’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:44.208 [nt.ItemStatePredictedEvent] - SonySimpleIP_Channel predicted to become 643
2019-03-06 21:18:44.211 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 644.0 to 643
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:44.758 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SCCHNN00000643.0000000’ result: ‘*SACHNNNNNNNNNNNNNNNNNN’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:18:44.761 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 643 to
==> /openhab/userdata/logs/openhab.log <==
2019-03-06 21:18:47.332 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 192.168.0.14:20060 - successful
2019-03-06 21:19:12.812 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SEVOLU################’
2019-03-06 21:19:12.912 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SEVOLU################’ result: ‘*SAVOLU0000000000000016’
2019-03-06 21:19:12.914 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SECHNN################’
2019-03-06 21:19:13.010 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send ‘*SECHNN################’ result: ‘*SACHNN00000644.0000000’
2019-03-06 21:19:13.011 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending ‘*SETCHN################’
==> /openhab/userdata/logs/events.log <==
2019-03-06 21:19:13.015 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from to 644.0

I have no log of going back to channel by IRCode Channel-Down but like you see at the IRCode Channel-Up part switching via this Binding-Channel works. The problem with switching channels via SonySimpleIP_Channel dont occur on tv-channels. The problem is only in the radio-channel range.

Simsal · March 7, 2019, 12:45pm

Yes that sounds like a massive overhead.
Maybe something like predictive refreshing?

Like remember last state and query every 30 seconds if this is still the same. If yes, nice, if not query all.

tmrobert8 · March 7, 2019, 1:24pm

@johfeu
Interesting - could you look in your log and find “Creating channel: appControl#arte-apptitle”. There should be two of them and I’m guessing it might be a case issue…

Thanks,
Tim

tmrobert8 · March 7, 2019, 1:31pm

I understand what you are saying but the line I quoted is what the TV is responding with. The SCCHNN is the command to set to the channel to 643. The response (SACHNN from the TV) is all "N"s - meaning the TV is telling me that it’s “Not Found”.

There is not much I can do from a binding standpoint here - looks like it’s probably a bug in the Sony TV code (considering that you can get to 643 via channel up/down - there really should be no reason you can’t set 643 directly). Note: the unparsable response is fixed in the next version…

tmrobert8 · March 7, 2019, 1:34pm

@Simsal

I’m actually going to table this one because I have a feeling when I integrate the sony audio addon (using websockets) - I’ll start getting the notifications again. We’ll see however…

Knut1507 · March 7, 2019, 7:08pm

Ok i didnt know if my explanation was sufficient. But i am sure we are talking about the same now. I unterstand its not a binding problem. To close this I tested today a little further and I discoverd it has nothing to do with the radio channels. None channel above 50 is valid via SonySimpleIP_Channel. In the following log i tried to set channel 51 by sending the number to SonySimpleIP_Channel. It fails and stays at 50. Then i use IRCode with Channel-Up and it goes to the channel 51.

    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:42:55.721 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SCCHNN00000050.0000000' result: '*SACHNN0000000000000000'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:06.870 [ome.event.ItemCommandEvent] - Item 'SonySimpleIP_Channel' received command 51
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:06.871 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SCCHNN00000051.0000000'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:06.874 [nt.ItemStatePredictedEvent] - SonySimpleIP_Channel predicted to become 51
    2019-03-07 19:43:06.876 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 50 to 51
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:07.437 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SCCHNN00000051.0000000' result: '*SACHNNNNNNNNNNNNNNNNNN'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:07.439 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 51 to
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:13.000 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'
    2019-03-07 19:43:13.103 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'
    2019-03-07 19:43:13.103 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'
    2019-03-07 19:43:13.305 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000050.0000000'
    2019-03-07 19:43:13.316 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:13.321 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from to 50.0
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:13.404 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHN000104376D67####'
    2019-03-07 19:43:13.405 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'
    2019-03-07 19:43:13.503 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCdvbc############'
    2019-03-07 19:43:13.504 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'
    2019-03-07 19:43:13.606 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000050.0000000'
    2019-03-07 19:43:13.608 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'
    2019-03-07 19:43:13.705 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHN000104376D67####'
    2019-03-07 19:43:13.706 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'
    2019-03-07 19:43:13.801 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPI0000000000000000'
    2019-03-07 19:43:23.394 [DEBUG] [g.sony.internal.AbstractThingHandler] - Checking connectivity to 192.168.0.14:20060 - successful
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:26.492 [ome.event.ItemCommandEvent] - Item 'SonySimpleIP_IRCode' received command Channel-Up
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:26.535 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Transformed Channel-Up with map file 'sonysimpleip.map' to 33
    2019-03-07 19:43:26.536 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SCIRCC0000000000000033'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:26.538 [nt.ItemStatePredictedEvent] - SonySimpleIP_IRCode predicted to become Channel-Up
    2019-03-07 19:43:26.541 [vent.ItemStateChangedEvent] - SonySimpleIP_IRCode changed from Power-On to Channel-Up
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:26.574 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SCIRCC0000000000000033' result: '*SAIRCC0000000000000000'
    2019-03-07 19:43:26.575 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPOWR################'
    2019-03-07 19:43:27.841 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPOWR################' result: '*SAPOWR0000000000000001'
    2019-03-07 19:43:27.842 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:27.966 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'
    2019-03-07 19:43:27.967 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'
    2019-03-07 19:43:28.065 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000051.0000000'
    ==&gt; /openhab/userdata/logs/events.log &lt;==
    2019-03-07 19:43:28.067 [vent.ItemStateChangedEvent] - SonySimpleIP_Channel changed from 50.0 to 51.0
    ==&gt; /openhab/userdata/logs/openhab.log &lt;==
    2019-03-07 19:43:28.069 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'
    2019-03-07 19:43:28.183 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHN000104376D68####'
    2019-03-07 19:43:28.184 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'
    2019-03-07 19:43:28.408 [DEBUG] [ny.internal.net.SocketChannelSession] - Dispatching response: *SNCHNN00000051.0000000
    2019-03-07 19:43:28.442 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
    *SAISRCdvbc############' to command '*SEISRC################'
    2019-03-07 19:43:31.446 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
    ' to command '*SEISRC################'
    2019-03-07 19:43:31.446 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEPIPI################'
    2019-03-07 19:43:31.533 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEPIPI################' result: '*SAPIPI0000000000000000'
    2019-03-07 19:43:31.534 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEVOLU################'
    2019-03-07 19:43:31.630 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEVOLU################' result: '*SAVOLU0000000000000016'
    2019-03-07 19:43:31.630 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'
    2019-03-07 19:43:31.726 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000051.0000000'
    2019-03-07 19:43:31.727 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'
    2019-03-07 19:43:31.820 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SETCHN################' result: '*SATCHN000104376D68####'
    2019-03-07 19:43:31.821 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SEISRC################'
    2019-03-07 19:43:31.950 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SEISRC################' result: '*SAISRCdvbc############'
    2019-03-07 19:43:31.950 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SECHNN################'
    2019-03-07 19:43:32.044 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Send '*SECHNN################' result: '*SACHNN00000051.0000000'
    2019-03-07 19:43:32.046 [DEBUG] [y.internal.simpleip.SimpleIpProtocol] - Sending '*SETCHN################'

So this may be limited to my tv model but if I want to set the channel to something higher than 50 i maybe have to use a rule that changes a set number by Setpoint or Selection to IRCodes with the Num command sending each digit on its own.

Furthermore i, even with the remote, can only switch to a radio channel if i use the “TV/RADIO” button first.

johfeu · March 7, 2019, 9:02pm

yes, its there twice

openhab.log:2019-03-07 21:50:42.387 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-apptitle accepting String of type scalarappcontrolapptitle
openhab.log:2019-03-07 21:50:42.390 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appicon accepting String of type scalarappcontrolappicon
openhab.log:2019-03-07 21:50:42.393 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appdata accepting String of type scalarappcontrolappdata
openhab.log:2019-03-07 21:50:42.396 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appstatus accepting Switch of type scalarappcontrolappstatus
openhab.log:2019-03-07 21:50:42.657 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-apptitle accepting String of type scalarappcontrolapptitle
openhab.log:2019-03-07 21:50:42.660 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appicon accepting String of type scalarappcontrolappicon
openhab.log:2019-03-07 21:50:42.663 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appdata accepting String of type scalarappcontrolappdata
openhab.log:2019-03-07 21:50:42.666 [DEBUG] [.internal.scalarweb.ScalarWebHandler] - Creating channel: appControl#arte-appstatus accepting Switch of type scalarappcontrolappstatus

what is the source of the arte app on the tv?

tmrobert8 · March 7, 2019, 9:09pm

No idea - it’s whatever application has “arte” in it’s title. The binding will strip all the non [A-Z,0-9,-_] characters from the title and use it. However, there is logic in it to make sure they are unique - I though maybe a case change happened and made a duplicate. But it doesn’t look like it - looks like a pure bug on my part that I’ll need to figure out. And you are sure you are using the latest binding (from March 3rd) because that’s the one that I added all the duplicate logic)…

johfeu · March 7, 2019, 9:25pm

Yes, I am using 2.5 03/03. Just checked on the TV, there are indeed two arte apps (ARTE and arte)

tmrobert8 · March 7, 2019, 9:27pm

Ah - okay then the next version fixes that (I’m checking for duplicates in a non-case manner now). Next release should happen tomorrow…

EDIT: you can work around it for now by deleting one of those apps

johfeu · March 7, 2019, 9:43pm

hm, i dont think i can remove/add any of the apps on this tv (KDL-48W585B)
they are both only listed under All Apps and not added to My Apps. Its not an AndroidTV

anon92890676 · March 8, 2019, 5:29pm

At this moment I use Ircc because the other method is not working (see preview post).

Now I have found a problem. When I take off the power of my tv, and take it on in the next day, the Ircc command don’t work. I must go to “things” and re-enter the Access Code. After this it works. Is this a problem of the binding?

tmrobert8 · March 8, 2019, 7:26pm

@anon92890676
That is probably a binding error - when you re-enter the access code, it forces the binding to restart and it reconnects. I’ll try to figure out why it’s not reconnecting automatically…