[Thermostat] Cannot set setpoint temperature with latest beta

alessandro-lac commented 5 years ago

I've recently updated to the latest binding beta9-2, but I can't change the setpoint temperature.

The system can successfully read it, but when I try to change it (either via PaperUI or Basic UI) I get an error.

==> /var/log/openhab2/events.log <==

2019-02-04 00:06:39.482 [vent.ItemStateChangedEvent] - iLR_setpointTemp changed from 22.5 ℃ to 23 ℃

==> /var/log/openhab2/openhab.log <==

2019-02-04 00:06:39.483 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== handleSetpointCommand() (command=23 ℃)

2019-02-04 00:06:39.488 [DEBUG] [.openwebnet.message.Thermoregulation] - ====TEMPERATURE 23.0 --> : <0230>

2019-02-04 00:06:39.493 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING ============>>>>>>>>>>>> #4#1#140230*3##

2019-02-04 00:06:39.495 [DEBUG] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## CANNOT read from socket: read returned -1

2019-02-04 00:06:39.499 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## read returned -1

2019-02-04 00:06:39.501 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## let's try with a NEW SESSION...

2019-02-04 00:06:39.504 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## Trying openCommandSession() socket.... (192.168.1.130:20000)

2019-02-04 00:06:39.508 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - ##gw-openwebnet-conn## ... starting HANDSHAKE ...

2019-02-04 00:06:39.510 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== #1##

2019-02-04 00:06:39.512 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> 990##

2019-02-04 00:06:39.516 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== 982##

2019-02-04 00:06:39.518 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1##

2019-02-04 00:06:39.522 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== *#14090200110810071304000006061300121207020710031105040511120103081115130303111503071102040904091503111012131306070013020311021103##

2019-02-04 00:06:39.526 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1105041310110512150806141514080407130103100908120303021303071311150914030703071109110700041008110110060706130305141105021013080714030703130602111208101213101214050405110515050406050111070610141402150205150303040709020903140313090406141504080201130504021515##

2019-02-04 00:06:39.530 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== *#13060703071312111210150505040106061301030807040707151204021509091103030208060701150107060303150904010900070705120411110614100405##

2019-02-04 00:06:39.533 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1##

2019-02-04 00:06:39.535 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## ... CONNECTED command session 192.168.1.130:20000

2019-02-04 00:06:39.537 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING ============>>>>>>>>>>>> #4#1#140230*3##

2019-02-04 00:06:39.650 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED <<<<<<<<<<<<============ #1##

2019-02-04 00:06:39.954 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler@1458c7d': org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

java.lang.ClassCastException: org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleSetpointCommand(OpenWebNetThermoregulationHandler.java:150) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleChannelCommand(OpenWebNetThermoregulationHandler.java:118) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.handleCommand(OpenWebNetThingHandler.java:117) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at com.sun.proxy.$Proxy127.handleCommand(Unknown Source) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:75) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]

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) [?:?]

The items is like this:

/ Thermostat Setup (Google Assitant/Alexa require thermostat items to be grouped together) / Group gLR_thermostat "Living Room Thermostat" [ "Thermostat", "Celcius" ] Number:Temperature iLR_temp "Temperature" (gLR_Thermostat) [ "CurrentTemperature" ]
String iLR_offset "Offset" (gLR_Thermostat)
Switch iLR_heating "Heating is" (gLR_Thermostat)
Switch iLR_cooling "Cooling is" (gLR_Thermostat)
Number:Temperature iLR_targetTemp "Target" (gLR_Thermostat)
String iLR_activeMode "Active Mode" (gLR_Thermostat)
String iLR_heatCool "HeatingCoolingMode" (gLR_Thermostat) [ "homekit:HeatingCoolingMode" ] Number:Temperature iLR_setpointTemp "Setpoint Temperature" (gLR_Thermostat) [ "TargetTemperature" ]
String iLR_setMode "Set Mode" (gLR_Thermostat)

/ Thermostat Setup (Google Assitant/Alexa require thermostat items to be grouped together) / Group gLR_thermostatP1 "First Floor Thermostat" [ "Thermostat", "Celcius" ] Number:Temperature iLR_tempP1 "Temperature" (gLR_thermostatP1) [ "CurrentTemperature" ]
String iLR_offsetP1 "Offset" (gLR_thermostatP1)
Switch iLR_heatingP1 "Heating is" (gLR_thermostatP1)
Switch iLR_coolingP1 "Cooling is" (gLR_thermostatP1)
Number:Temperature iLR_targetTempP1 "Target" (gLR_thermostatP1)
String iLR_activeModeP1 "Active Mode" (gLR_thermostatP1)
String iLR_heatCoolP1 "HeatingCoolingMode" (gLR_thermostatP1) [ "homekit:HeatingCoolingMode" ]
Number:Temperature iLR_setpointTempP1 "Setpoint Temperature" (gLR_thermostatP1) [ "TargetTemperature" ]
String iLR_setModeP1 "Set Mode" (gLR_thermostatP1)

And the binding is done via paper UI :

I remember it was working prior to the update (but I'm not 100% sure about it). I tried to completely remove it from PaperUI and recreate it from scratch, but still no luck... I see that a similar issue (#45) was fixed with this beta, any chance it created this issue?

Alessandro

mvalla commented 5 years ago

re-post the log using "inser code <>" button above the text box , otherwise lg messages are not readable correctly

alessandro-lac commented 5 years ago

That was my first attempt, apparently, it messes them up anyway. I'll try below, maybe it's a problem of the preview...

Logs:


==> /var/log/openhab2/events.log <==

2019-02-04 00:06:39.482 [vent.ItemStateChangedEvent] - iLR_setpointTemp changed from 22.5 ℃ to 23 ℃

==> /var/log/openhab2/openhab.log <==

2019-02-04 00:06:39.483 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== handleSetpointCommand() (command=23 ℃)

2019-02-04 00:06:39.488 [DEBUG] [.openwebnet.message.Thermoregulation] - ====TEMPERATURE 23.0 --> : <0230>

2019-02-04 00:06:39.493 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#4*#1*#14*0230*3##

2019-02-04 00:06:39.495 [DEBUG] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## CANNOT read from socket: read returned -1

2019-02-04 00:06:39.499 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## read returned -1

2019-02-04 00:06:39.501 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## let's try with a NEW SESSION...

2019-02-04 00:06:39.504 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## Trying openCommandSession() socket....  (192.168.1.130:20000)

2019-02-04 00:06:39.508 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - ##gw-openwebnet-conn## ... starting HANDSHAKE ... 

2019-02-04 00:06:39.510 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *#*1##

2019-02-04 00:06:39.512 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING    ======>>>>>> *99*0##

2019-02-04 00:06:39.516 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *98*2##

2019-02-04 00:06:39.518 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING    ======>>>>>> *#*1##

2019-02-04 00:06:39.522 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *#14090200110810071304000006061300121207020710031105040511120103081115130303111503071102040904091503111012131306070013020311021103##

2019-02-04 00:06:39.526 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING    ======>>>>>> *#11050413101105121508061415140804071301031009081203030213030713111509140307030711091107000410081101100607061303051411050210130807*14030703130602111208101213101214050405110515050406050111070610141402150205150303040709020903140313090406141504080201130504021515##

2019-02-04 00:06:39.530 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED   <<<<<<====== *#13060703071312111210150505040106061301030807040707151204021509091103030208060701150107060303150904010900070705120411110614100405##

2019-02-04 00:06:39.533 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING    ======>>>>>> *#*1##

2019-02-04 00:06:39.535 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## ... CONNECTED command session 192.168.1.130:20000

2019-02-04 00:06:39.537 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#4*#1*#14*0230*3##

2019-02-04 00:06:39.650 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##

2019-02-04 00:06:39.954 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler@1458c7d': org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

java.lang.ClassCastException: org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

    at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleSetpointCommand(OpenWebNetThermoregulationHandler.java:150) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

    at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleChannelCommand(OpenWebNetThermoregulationHandler.java:118) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

    at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.handleCommand(OpenWebNetThingHandler.java:117) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

    at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

    at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [101:org.eclipse.smarthome.core:0.10.0.oh230]

    at com.sun.proxy.$Proxy127.handleCommand(Unknown Source) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

    at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:75) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

    at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

    at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

    at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

    at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]

    at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]

    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) [?:?]

mvalla commented 5 years ago

ok I understand what is causing the error in the log. questions:

which version of openHAB are you using?
which model of BTicino thermostat are you using?
do you also have a thermo central unit ? (3500 / ...)
does the temperature get actually changed on the real thermostat when you send the command from PaperUI ?
can you post more lines of log after the error? in fact, the thermostat should answer to the command (which is actually sent to the BUS), and confirm the setTemp change, so the PaperUI should change its value anyway...

alessandro-lac commented 5 years ago

Hi, here the answers:

OpenHAB 2.3.0-1
I have the following setup: 1 F430/2 and 2 H4691 connected to it (one for each channel)
I don't think so (is there a way for me to check?)
The flow is the following: I change the temperature via PaperUI, the value is changed in the UI but doesn't change the on the thermostat. In PaperUI it changes back to the previous value after few seconds
Sure, here you go:

==> /var/log/openhab2/events.log <==

2019-02-05 10:51:07.198 [ome.event.ItemCommandEvent] - Item 'iLR_setpointTemp' received command 22 ℃

2019-02-05 10:51:07.242 [vent.ItemStateChangedEvent] - iLR_setpointTemp changed from 20.5 ℃ to 22 ℃

==> /var/log/openhab2/openhab.log <==

2019-02-05 10:51:07.247 [DEBUG] [ebnet.handler.OpenWebNetThingHandler] - ==OWN:ThingHandler== handleCommand() (command=22 ℃ - channel=openwebnet:bus_thermostat:0540dbbe:1:setpointTemperature)

2019-02-05 10:51:07.255 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== handleSetpointCommand() (command=22 ℃)

2019-02-05 10:51:07.262 [DEBUG] [.openwebnet.message.Thermoregulation] - ====TEMPERATURE 22.0 --> : <0220>

2019-02-05 10:51:07.272 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING ============>>>>>>>>>>>> #4#1#140220*3##

2019-02-05 10:51:07.288 [DEBUG] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## CANNOT read from socket: read returned -1

2019-02-05 10:51:07.295 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## read returned -1

2019-02-05 10:51:07.297 [DEBUG] [g.openwebnet.bus.MyHomeJavaConnector] - ##gw-openwebnet-connector## let's try with a NEW SESSION...

2019-02-05 10:51:07.299 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## Trying openCommandSession() socket.... (192.168.1.130:20000)

2019-02-05 10:51:07.303 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - ##gw-openwebnet-conn## ... starting HANDSHAKE ...

2019-02-05 10:51:07.305 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== #1##

2019-02-05 10:51:07.307 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> 990##

2019-02-05 10:51:07.311 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== 982##

2019-02-05 10:51:07.313 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1##

2019-02-05 10:51:07.317 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== *#14130615101503000415021414001405050308080514080505130903020210070905070002041000030315131101110305050300060511051211051207010909##

2019-02-05 10:51:07.321 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1305000110120308090207050502100708000009141302080902000807081100020613040711000313020012060401040000150114120606111200151212060108080008050410121411131407121012091415060606070001100411071113130104100707100303131100080404040102021105010302051507071503100715##

2019-02-05 10:51:07.325 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM RECEIVED <<<<<<====== *#05010708081103080303091501100703080112041004120704030212090902101212020008130802110613000115061405031504120412021014060206141106##

2019-02-05 10:51:07.328 [DEBUG] [org.openwebnet.bus.MyHomeConnection ] - COM SENDING ======>>>>>> #1##

2019-02-05 10:51:07.330 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - ##gw-openwebnet-socket## ... CONNECTED command session 192.168.1.130:20000

2019-02-05 10:51:07.332 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING ============>>>>>>>>>>>> #4#1#140220*3##

2019-02-05 10:51:07.446 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED <<<<<<<<<<<<============ #1##

2019-02-05 10:51:07.749 [ERROR] [nal.common.AbstractInvocationHandler] - An error occurred while calling method 'ThingHandler.handleCommand()' on 'org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler@11fcc75': org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

java.lang.ClassCastException: org.eclipse.smarthome.core.library.types.QuantityType cannot be cast to org.eclipse.smarthome.core.library.types.DecimalType

at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleSetpointCommand(OpenWebNetThermoregulationHandler.java:150) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.openhab.binding.openwebnet.handler.OpenWebNetThermoregulationHandler.handleChannelCommand(OpenWebNetThermoregulationHandler.java:118) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.openhab.binding.openwebnet.handler.OpenWebNetThingHandler.handleCommand(OpenWebNetThingHandler.java:117) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at org.eclipse.smarthome.core.internal.common.InvocationHandlerSync.invoke(InvocationHandlerSync.java:59) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at com.sun.proxy.$Proxy127.handleCommand(Unknown Source) [220:org.openhab.binding.openwebnet:2.4.0.201901181816]

at org.eclipse.smarthome.core.thing.internal.profiles.ProfileCallbackImpl.handleCommand(ProfileCallbackImpl.java:75) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

at org.eclipse.smarthome.core.thing.internal.profiles.SystemDefaultProfile.onCommandFromItem(SystemDefaultProfile.java:49) [108:org.eclipse.smarthome.core.thing:0.10.0.oh230]

at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) ~[?:?]

at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) ~[?:?]

at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]

at java.lang.reflect.Method.invoke(Method.java:498) ~[?:?]

at org.eclipse.smarthome.core.internal.common.AbstractInvocationHandler.invokeDirect(AbstractInvocationHandler.java:153) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at org.eclipse.smarthome.core.internal.common.Invocation.call(Invocation.java:53) [101:org.eclipse.smarthome.core:0.10.0.oh230]

at java.util.concurrent.FutureTask.run(FutureTask.java:266) [?:?]

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) [?:?]

2019-02-05 10:51:11.662 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #4100224##

2019-02-05 10:51:11.667 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.1

2019-02-05 10:51:11.671 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateTemperature() for thing: openwebnet:bus_thermostat:0540dbbe:1

==> /var/log/openhab2/events.log <==

2019-02-05 10:51:11.694 [vent.ItemStateChangedEvent] - iLR_temp changed from 22.3 ℃ to 22.4 ℃

==> /var/log/openhab2/openhab.log <==

2019-02-05 10:51:11.957 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #41120205*3##

2019-02-05 10:51:11.962 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.1

2019-02-05 10:51:11.966 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< 41*1##

2019-02-05 10:51:11.968 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateTargetTemp() for thing: openwebnet:bus_thermostat:0540dbbe:1

2019-02-05 10:51:11.971 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.1

2019-02-05 10:51:11.977 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateMode() for thing: openwebnet:bus_thermostat:0540dbbe:1 msg=<41*1##>

2019-02-05 10:51:11.987 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateMode() mode not processed: msg=<41*1##>

2019-02-05 10:51:11.992 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateThermoFunction() for thing: openwebnet:bus_thermostat:0540dbbe:1

2019-02-05 10:51:11.995 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateHeatingCoolingMode() for thing: openwebnet:bus_thermostat:0540dbbe:1

2019-02-05 10:51:12.263 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #41140205*3##

2019-02-05 10:51:12.271 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.1

2019-02-05 10:51:12.275 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateSetpoint() for thing: openwebnet:bus_thermostat:0540dbbe:1

==> /var/log/openhab2/events.log <==

2019-02-05 10:51:12.303 [vent.ItemStateChangedEvent] - iLR_setpointTemp changed from 22 ℃ to 20.5 ℃

==> /var/log/openhab2/openhab.log <==

2019-02-05 10:51:12.338 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #411300##

2019-02-05 10:51:12.345 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.1

2019-02-05 10:51:12.350 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateLocalMode() for thing: openwebnet:bus_thermostat:0540dbbe:1

2019-02-05 10:51:12.355 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateLocalMode() new localMode=NORMAL

2019-02-05 10:51:12.906 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #4200223##

2019-02-05 10:51:12.912 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.2

2019-02-05 10:51:12.915 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateTemperature() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.204 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #42120205*3##

2019-02-05 10:51:13.207 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.2

2019-02-05 10:51:13.209 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateTargetTemp() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.215 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< 41*2##

2019-02-05 10:51:13.218 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.2

2019-02-05 10:51:13.220 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateMode() for thing: openwebnet:bus_thermostat:0540dbbe:2 msg=<41*2##>

2019-02-05 10:51:13.222 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateMode() mode not processed: msg=<41*2##>

2019-02-05 10:51:13.225 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateThermoFunction() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.227 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateHeatingCoolingMode() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.503 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #42140205*3##

2019-02-05 10:51:13.508 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.2

2019-02-05 10:51:13.512 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateSetpoint() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.587 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< #421300##

2019-02-05 10:51:13.592 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN== ownId=4.2

2019-02-05 10:51:13.597 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateLocalMode() for thing: openwebnet:bus_thermostat:0540dbbe:2

2019-02-05 10:51:13.601 [DEBUG] [er.OpenWebNetThermoregulationHandler] - ==OWN:ThermoHandler== updateLocalMode() new localMode=NORMAL

mvalla commented 5 years ago

Hi, can you use this official OpenWebNet Client from BTicino then put the IP address of your BTicino gateway and password (select HMAC if text password) and then:

presse the OPEN EVENT button on the client to start a event monitoring session
change your H4691 thermostat to 18 ℃ (using the physical device)
go back to the bticino client and send this openwebnet command:
- *#4*1*#14*0220*3##
then see if the H4691 thermostat has changed its temp from 18 to 22℃
take pictures of the log of the OWN client window (is't not possible to export text), use multiple pictures to show all log if needed, and copy them here

m

alessandro-lac commented 5 years ago

Sending the command through the OWN client works as I see the temperature changing from 18 to 22°.

Attached the screenshot of the logs

Ale

mvalla commented 5 years ago

ok @AlessandroLacroce can you also send me the output of: *#1004*0*7## and then in another window of: *#4*1## bye M

alessandro-lac commented 5 years ago

There you go:

I executed the first one on the left (it produced up till the "#1##===#10040*7##===" output) and then the second one the right (some output was produced into the first window too).

Bye, Ale

mvalla / openhab-addons

[Thermostat] Cannot set setpoint temperature with latest beta #56