Sony Devices Binding

openhab2
Tags: #<Tag:0x00007f6ce628e380>

(Jonas) #321

Hi!

Over time i am getting more and more issues.
i don’t know where to search for a solution.
it does take a very long time to get the actual status of my power for example.
i am using this in a rule to power my speakers so it’s really annoying.
if i send the command power on it’s working instantly.

can you please help me?


(Jonas) #322

Hi! I am back from holiday now. my openhab server was running now for 10 days without any problems (i can still control everything) but the binding got stuck.
is there a way to restart the binding from time to time so it is more stable? did you maybe release an update or do you plan to?


(Jonas) #323

maybe it’s my tv …

what changes did you make in the settings of your tv to make it run smoothly?

EDIT:
After i deleted the TV and added it again, it worked. But the Token is still set to RQST. Where can i see the token to add it manually (maybe it’s more stable then)


(Justas) #324

Hi Tmrobert8,

Hope this is the log file you asked for to look at.
I accessed it in http://openhabianpi:9001/
If you would like to see another log, please tell me where to enable it.

So questions remain:

  1. Volume control on Scalar is not available.
  2. Restarted, as bundle:stop -> bundle:start. see log file below in another post.

`

tail -F /var/log/openhab2/openhab.log /var/log/openhab2/events.log
Filter
 	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-06 18:48:02.230 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'usb' and source 'usb:recStorage': Storage doesn't exist.
java.io.IOException: Storage doesn't exist.
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-06 18:48:02.575 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurring getting the playing content info: Display Is Turned off
java.io.IOException: Display Is Turned off
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:47)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:613)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-06 18:48:03.360 [INFO ] [.internal.scalarweb.ScalarWebHandler] - Starting state polling every 5 seconds
==> /var/log/openhab2/events.log <==
2017-10-06 18:48:03.417 [ThingUpdatedEvent         ] - Thing 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' has been updated.
==> /var/log/openhab2/openhab.log <==
2017-10-08 18:28:05.426 [INFO ] [.internal.scalarweb.ScalarWebHandler] - Attempting connection to Scalar Web device...
==> /var/log/openhab2/events.log <==
2017-10-08 18:28:05.623 [ThingUpdatedEvent         ] - Thing 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' has been updated.
==> /var/log/openhab2/openhab.log <==
2017-10-08 18:28:06.766 [INFO ] [web.protocols.ScalarWebLoginProtocol] - Command map already defined - ignoring: /etc/openhab2/transform/scalar-1f75c1ed-7cba-4668-b120-dce4b390c0e4.map
2017-10-08 18:28:09.781 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=1': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:09.845 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=2': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:09.912 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=3': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:10.000 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=4': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:10.211 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'usb' and source 'usb:recStorage': Storage doesn't exist.
java.io.IOException: Storage doesn't exist.
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:10.582 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurring getting the playing content info: Display Is Turned off
java.io.IOException: Display Is Turned off
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:47)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:613)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 18:28:11.316 [INFO ] [.internal.scalarweb.ScalarWebHandler] - Starting state polling every 5 seconds
==> /var/log/openhab2/events.log <==
2017-10-08 18:28:11.368 [ThingUpdatedEvent         ] - Thing 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' has been updated.
2017-10-08 18:58:26.764 [ItemStateChangedEvent     ] - TV_PowerStatus changed from OFF to ON
2017-10-08 18:58:28.109 [ItemStateChangedEvent     ] - LRT_HD changed from OFF to ON
2017-10-08 18:58:42.699 [ItemStateChangedEvent     ] - LRT_HD changed from ON to OFF
2017-10-08 18:58:42.712 [ItemStateChangedEvent     ] - TV3 changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2017-10-08 18:58:45.301 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
*SATCHN21B800010008####' to command '*SETCHN################'
2017-10-08 18:58:48.308 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
' to command '*SETCHN################'
==> /var/log/openhab2/events.log <==
2017-10-08 19:00:16.749 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 13 to 9
2017-10-08 19:00:17.281 [ItemStateChangedEvent     ] - TV3 changed from ON to OFF
2017-10-08 19:02:12.070 [ItemStateChangedEvent     ] - TV3 changed from OFF to ON
2017-10-08 19:05:22.392 [ItemStateChangedEvent     ] - TV3 changed from ON to OFF
2017-10-08 19:07:01.843 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 9 to 12
==> /var/log/openhab2/openhab.log <==
2017-10-08 19:10:40.661 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
*SAVOLU0000000000000012' to command '*SEVOLU################'
==> /var/log/openhab2/events.log <==
2017-10-08 19:10:42.332 [ItemStateChangedEvent     ] - TV3 changed from OFF to ON
==> /var/log/openhab2/openhab.log <==
2017-10-08 19:10:43.669 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
' to command '*SEVOLU################'
==> /var/log/openhab2/events.log <==
2017-10-08 19:18:12.157 [ItemStateChangedEvent     ] - TV3 changed from ON to OFF
==> /var/log/openhab2/openhab.log <==
2017-10-08 19:18:31.843 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
*SAPIPI0000000000000000' to command '*SEPIPI################'
==> /var/log/openhab2/events.log <==
2017-10-08 19:18:32.258 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 12 to 14
==> /var/log/openhab2/openhab.log <==
2017-10-08 19:18:34.850 [WARN ] [y.internal.simpleip.SimpleIpProtocol] - Unparsable response '
' to command '*SEPIPI################'
==> /var/log/openhab2/events.log <==
2017-10-08 19:24:19.402 [ItemStateChangedEvent     ] - TV3 changed from OFF to ON
2017-10-08 19:28:02.140 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 14 to 13
2017-10-08 19:28:07.323 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 13 to 11
2017-10-08 19:42:02.425 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 11 to 0
2017-10-08 19:43:42.362 [ItemStateChangedEvent     ] - TV_ScalarVolume changed from 0 to 11```

`

(Justas) #325

Log after restart:

2017-10-08 21:07:29.831 [hingStatusInfoChangedEvent] - 'sony:simpleip:fcc66ee8-5285-472e-bb29-1c0fc74600df' changed from ONLINE to UNINITIALIZED
2017-10-08 21:07:29.854 [hingStatusInfoChangedEvent] - 'sony:simpleip:fcc66ee8-5285-472e-bb29-1c0fc74600df' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2017-10-08 21:07:29.857 [hingStatusInfoChangedEvent] - 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' changed from ONLINE to UNINITIALIZED
2017-10-08 21:07:29.871 [hingStatusInfoChangedEvent] - 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' changed from UNINITIALIZED to UNINITIALIZED (HANDLER_MISSING_ERROR)
2017-10-08 21:08:45.234 [hingStatusInfoChangedEvent] - 'sony:simpleip:fcc66ee8-5285-472e-bb29-1c0fc74600df' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
==> /var/log/openhab2/openhab.log <==
2017-10-08 21:08:45.299 [INFO ] [.internal.scalarweb.ScalarWebHandler] - Attempting connection to Scalar Web device...
==> /var/log/openhab2/events.log <==
2017-10-08 21:08:45.300 [hingStatusInfoChangedEvent] - 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' changed from UNINITIALIZED (HANDLER_MISSING_ERROR) to INITIALIZING
2017-10-08 21:08:46.264 [hingStatusInfoChangedEvent] - 'sony:simpleip:fcc66ee8-5285-472e-bb29-1c0fc74600df' changed from INITIALIZING to ONLINE
==> /var/log/openhab2/openhab.log <==
2017-10-08 21:08:46.257 [INFO ] [y.internal.simpleip.SimpleIpProtocol] - Command map already defined - ignoring: /etc/openhab2/transform/simpleip-fcc66ee8-5285-472e-bb29-1c0fc74600df.map
2017-10-08 21:08:46.922 [INFO ] [web.protocols.ScalarWebLoginProtocol] - Command map already defined - ignoring: /etc/openhab2/transform/scalar-1f75c1ed-7cba-4668-b120-dce4b390c0e4.map
2017-10-08 21:08:50.043 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=1': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 21:08:50.126 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=2': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 21:08:50.204 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=3': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 21:08:50.650 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'fav' and source 'fav:tv?id=4': Illegal Argument
java.io.IOException: Illegal Argument
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
==> /var/log/openhab2/events.log <==
2017-10-08 21:08:50.924 [hingStatusInfoChangedEvent] - 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' changed from INITIALIZING to OFFLINE (COMMUNICATION_ERROR): Storage doesn't exist.
==> /var/log/openhab2/openhab.log <==
2017-10-08 21:08:50.951 [ERROR] [protocols.ScalarWebAvContentProtocol] - Exception occurred retrieving the content list for scheme 'usb' and source 'usb:recStorage': Storage doesn't exist.
java.io.IOException: Storage doesn't exist.
	at org.openhab.binding.sony.internal.net.HttpResponse.createException(HttpResponse.java:128)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.AbstractScalarWebProtocol.execute(AbstractScalarWebProtocol.java:53)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebAvContentProtocol.getChannelDescriptors(ScalarWebAvContentProtocol.java:186)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.protocols.ScalarWebProtocolFactory.getChannelDescriptors(ScalarWebProtocolFactory.java:87)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.initializeTask(ScalarWebHandler.java:191)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler.access$2(ScalarWebHandler.java:155)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at org.openhab.binding.sony.internal.scalarweb.ScalarWebHandler$2.run(ScalarWebHandler.java:150)[224:org.openhab.binding.sony:2.2.0.201707261512]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)[:1.8.0_121]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)[:1.8.0_121]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)[:1.8.0_121]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)[:1.8.0_121]
	at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]
2017-10-08 21:08:52.293 [INFO ] [.internal.scalarweb.ScalarWebHandler] - Starting state polling every 5 seconds
==> /var/log/openhab2/events.log <==
2017-10-08 21:08:52.365 [ThingUpdatedEvent         ] - Thing 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' has been updated.
2017-10-08 21:08:52.372 [hingStatusInfoChangedEvent] - 'sony:scalar:1f75c1ed-7cba-4668-b120-dce4b390c0e4' changed from OFFLINE (COMMUNICATION_ERROR): Storage doesn't exist. to ONLINE

(Justas) #326

You can restart binding by entering this:

 ssh openhab@localhost -p 8101 bundle:restart org.openhab.binding.sony

after you will need to enter password, which default is “habopen”

I found some info here http://docs.openhab.org/administration/console.html


(Justas) #327

As I am new to openhab, could you please write down short step by step guide?
Or give a link to some tutorial, how to do it from openhab paper UI, if that is possible at all.


(Jonas) #328

Hi Justas,

first of all, you should read the tutorials on the openhab page (what is a thing, an item, a rule etc.). This helped me a lot but of course i got lots of help from the community! :wink:

I also had the issue, that sometimes volume control is not available. now it is (after a few restarts) and now its working for me.
try to delete your tv and add it again.
unfortunately i cant help with your logs.
but you can assign items to the scalar controls found in the paper ui (i did that). for instance:

Switch WoZi_Schalter_TV_Amazon “Amazon” (GrWoZi) [“Switchable”] {channel=“sony:scalar:abfd6a30-96f2-4f01-a93e-942496750b50:appControl-com__0sony__0dtv__0com__0amazon__0aiv__0eu__0com__0amazon__0ignition__0IgnitionActivity-appstatus”}


(Tim Roberts) #329

Jonas,

Been in the hospital for a bit and will try to catch up with this later today or tomorrow…

Tim


(Tim Roberts) #330

@Justas

Interesting logs - definitely and issue on the binding side and I’ll have to make a change for that.

Tim


(Jonas) #331

No hurrys!
Hope you get well soon!


(Jonas) #332

I have some updates.
My TV restarted itself (i guess because of a bug) these days and after that the binding did not work anymore (although the server was running again on the TV). I restarted my raspberry and everything worked again. Maybe there is some kind of timeout or so?


(Tim Roberts) #333

More than likely my binding isn’t retrying to go online for some reason (maybe a more fatal error). If you could, next time it happens - post a little of the log file when it’s trying to reconnect (or whatever error is occurring). I’m wrapping up getting the NEEO binding approved and then I’ll get back to this…


(Jonas) #334

it’s stuck now. but the logs have not update since yesterday.
is there another way i can help? maybe trigger a status update?


(Tim Roberts) #335

Do you have debug turned on for the sony binding? I don’t know what your expertise level is - let me know if you don’t know how to do that and I’ll give you some instructions


(Jonas) #336

nope, didn’t activate the debugging and don’t know hot to do that.

greetings


(James) #337

Hi All, is this binding in the unstable/latest branch - just bumped up from stable and don’t see, still a OH noob.
Did look on git but could not find the packages - just the whole bindings tree.
Have 4 Sony’s dating back a good few years to the present android versions so would love to help to test.


(Miika Jukka) #338

This is not included in any of the builds so far. You can find the latest .zip file from this pull request: https://github.com/openhab/openhab2-addons/pull/1249

After unpacking just copy .jar file to OH addons folder.


(James) #339

Thanks Miika,
Could you let me know the addons path, i see a few when using locate.

Ah /usr/share/ - got it :slight_smile:


(James) #340

Bindings working here on my KD-49XD8099 (latest Bravia build)
Volume power and which inputs selected but not tried with others yet :slight_smile:

Thanks for this binding and all the work that’s gone into it guys!