Discovering automation things with MyHomeServer1

Jeby057 commented 5 years ago

Hi,

First of all, thank you for your work ! This project is really cool !

About my gateway, I have a MyHomeServer1. My web-server is perfectly recognized by OpenHab (installed on DSM) and I can find any on/off switch of my installation. However, I have some issues to discover dimmer and automation things.

About the dimmer things, I found a workaround : I need to turn on the light. If the light is off, I can't find it.

About the automation things, I have some errors in the log file : [...] 2018-12-22 17:39:51.864 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: 2033## [...]

And when I create manually an automation thing with WHERE=033 (for example), I have an UNKNOWN state.

I hope that this can help you to improve this project. Don't hesitate to contact me if you have questions or to test new versions.

mvalla commented 5 years ago

As clearly explained in the README, dimmers are found as dimmers only if they are ON and dimmed. Otherwise in any case they should be found as normal switches. If they are not found at all, then I need more information. Delete the dimmers you have configured, set log level to DEBUG (see README how) and start a new discovery from Inbox, then send me your openhab.log file (better send it using PM in the openHAB community).

Regarding the Automation not discovered, same info is needed:

openHAB & binding version installed
log set to DEBUG
openhab.log file just after a new discovery has been tested

The log line you posted here has not been put under "code fences" so is not useful, and more log is needed at DEBUG level to understand what is going on with your Automation device and which messages are received from MyHomeServer1.

bye M

Jeby057 commented 5 years ago

Hi

Thank you for your quickly answer.

OK for the dimmers.

You can find here :

the OpenHab version : 2.3.0.005
the OpenWebNet binding module version : 2.4.0-b8
the log file with DEBUG mode :

2018-12-22 20:17:12.112 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#2*0##
2018-12-22 20:17:12.186 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*11##
2018-12-22 20:17:12.187 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=11 has NO THING associated, ignoring it
2018-12-22 20:17:12.223 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*22##
2018-12-22 20:17:12.224 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=22 has NO THING associated, ignoring it
2018-12-22 20:17:12.259 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0015##
2018-12-22 20:17:12.260 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0015 has NO THING associated, ignoring it
2018-12-22 20:17:12.295 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*1*0014##
2018-12-22 20:17:12.296 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0014 has NO THING associated, ignoring it
2018-12-22 20:17:12.333 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*21##
2018-12-22 20:17:12.334 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=21 has NO THING associated, ignoring it
2018-12-22 20:17:12.377 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0013##
2018-12-22 20:17:12.377 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0013 has NO THING associated, ignoring it
2018-12-22 20:17:12.419 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0115##
2018-12-22 20:17:12.420 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0115 has NO THING associated, ignoring it
2018-12-22 20:17:12.463 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0012##
2018-12-22 20:17:12.464 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0012 has NO THING associated, ignoring it
2018-12-22 20:17:12.506 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0114##
2018-12-22 20:17:12.507 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0114 has NO THING associated, ignoring it
2018-12-22 20:17:12.550 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0011##
2018-12-22 20:17:12.551 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0011 has NO THING associated, ignoring it
2018-12-22 20:17:12.600 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0113##
2018-12-22 20:17:12.601 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0113 has NO THING associated, ignoring it
2018-12-22 20:17:12.650 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0010##
2018-12-22 20:17:12.651 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0010 has NO THING associated, ignoring it
2018-12-22 20:17:12.693 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0112##
2018-12-22 20:17:12.694 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0112 has NO THING associated, ignoring it
2018-12-22 20:17:12.729 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*09##
2018-12-22 20:17:12.730 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=09 has NO THING associated, ignoring it
2018-12-22 20:17:12.774 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0111##
2018-12-22 20:17:12.775 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0111 has NO THING associated, ignoring it
2018-12-22 20:17:12.822 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*08##
2018-12-22 20:17:12.823 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=08 has NO THING associated, ignoring it
2018-12-22 20:17:12.854 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*0110##
2018-12-22 20:17:12.855 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0110 has NO THING associated, ignoring it
2018-12-22 20:17:12.892 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*07##
2018-12-22 20:17:12.893 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=07 has NO THING associated, ignoring it
2018-12-22 20:17:12.934 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*06##
2018-12-22 20:17:12.935 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=06 has NO THING associated, ignoring it
2018-12-22 20:17:12.973 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*33##
2018-12-22 20:17:12.974 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*033##
2018-12-22 20:17:12.976 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=33 has NO THING associated, ignoring it
2018-12-22 20:17:13.012 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*05##
2018-12-22 20:17:13.014 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=05 has NO THING associated, ignoring it
2018-12-22 20:17:13.052 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*031##
2018-12-22 20:17:13.051 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*31##
2018-12-22 20:17:13.053 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=31 has NO THING associated, ignoring it
2018-12-22 20:17:13.090 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*04##
2018-12-22 20:17:13.091 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=04 has NO THING associated, ignoring it
2018-12-22 20:17:13.130 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*19##
2018-12-22 20:17:13.131 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=19 has NO THING associated, ignoring it
2018-12-22 20:17:13.169 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*03##
2018-12-22 20:17:13.170 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=03 has NO THING associated, ignoring it
2018-12-22 20:17:13.209 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*18##
2018-12-22 20:17:13.210 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=18 has NO THING associated, ignoring it
2018-12-22 20:17:13.247 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*02##
2018-12-22 20:17:13.248 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=02 has NO THING associated, ignoring it
2018-12-22 20:17:13.286 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*01##
2018-12-22 20:17:13.287 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=01 has NO THING associated, ignoring it
2018-12-22 20:17:13.326 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*17##
2018-12-22 20:17:13.327 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=17 has NO THING associated, ignoring it
2018-12-22 20:17:13.365 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*24##
2018-12-22 20:17:13.366 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=24 has NO THING associated, ignoring it
2018-12-22 20:17:13.405 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*16##
2018-12-22 20:17:13.407 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=16 has NO THING associated, ignoring it
2018-12-22 20:17:13.450 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*23##
2018-12-22 20:17:13.451 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=23 has NO THING associated, ignoring it
2018-12-22 20:17:13.490 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*15##
2018-12-22 20:17:13.491 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=15 has NO THING associated, ignoring it
2018-12-22 20:17:13.539 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*42##
2018-12-22 20:17:13.541 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=42 has NO THING associated, ignoring it
2018-12-22 20:17:13.584 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*14##
2018-12-22 20:17:13.585 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=14 has NO THING associated, ignoring it
2018-12-22 20:17:13.632 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*41##
2018-12-22 20:17:13.633 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=41 has NO THING associated, ignoring it
2018-12-22 20:17:13.678 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*13##
2018-12-22 20:17:13.679 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=13 has NO THING associated, ignoring it
2018-12-22 20:17:13.720 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*44##
2018-12-22 20:17:13.720 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*044##
2018-12-22 20:17:13.721 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=44 has NO THING associated, ignoring it
2018-12-22 20:17:13.760 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*12##
2018-12-22 20:17:13.761 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=12 has NO THING associated, ignoring it
2018-12-22 20:17:13.804 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*0*26##
2018-12-22 20:17:13.806 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=26 has NO THING associated, ignoring it
2018-12-22 20:17:13.849 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *1*3*25##
2018-12-22 20:17:13.850 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightState() for thing: openwebnet:bus_dimmer:c27aa453:25
2018-12-22 20:17:13.851 [DEBUG] [et.handler.OpenWebNetLightingHandler] - ==OWN:LightingHandler== updateLightBrightnessState() msg=<*1*3*25##>
2018-12-22 20:17:13.851 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $$$ START---updateLightBr latestBriWhat=3 latestBriBeforeOff=-1 brightnessLevelRequested=false
2018-12-22 20:17:13.852 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $bri now=1.545506233852E12 delta=1.0385352E7
2018-12-22 20:17:13.853 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $bri update from network -> level should be present in WHAT part of the message
2018-12-22 20:17:13.853 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $bri current level=3 ----> new level=3
2018-12-22 20:17:13.854 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $bri no change
2018-12-22 20:17:13.854 [DEBUG] [et.handler.OpenWebNetLightingHandler] - $$$ END  ---updateLightBr latestBriWhat=3 latestBriBeforeOff=-1 brightnessLevelRequested=false
2018-12-22 20:17:13.895 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*37##
2018-12-22 20:17:13.896 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*037##
2018-12-22 20:17:13.897 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=37 has NO THING associated, ignoring it
2018-12-22 20:17:13.942 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*35##
2018-12-22 20:17:13.942 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*035##
2018-12-22 20:17:13.943 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=35 has NO THING associated, ignoring it
2018-12-22 20:17:13.990 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*48##
2018-12-22 20:17:13.991 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*048##
2018-12-22 20:17:13.992 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=48 has NO THING associated, ignoring it
2018-12-22 20:17:14.039 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*46##
2018-12-22 20:17:14.039 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*046##
2018-12-22 20:17:14.040 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=46 has NO THING associated, ignoring it
2018-12-22 20:17:14.090 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0210##
2018-12-22 20:17:14.090 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00210##
2018-12-22 20:17:14.092 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0210 has NO THING associated, ignoring it
2018-12-22 20:17:14.142 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*28##
2018-12-22 20:17:14.142 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*028##
2018-12-22 20:17:14.143 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=28 has NO THING associated, ignoring it
2018-12-22 20:17:14.193 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0311##
2018-12-22 20:17:14.193 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00311##
2018-12-22 20:17:14.195 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0311 has NO THING associated, ignoring it
2018-12-22 20:17:14.248 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*39##
2018-12-22 20:17:14.249 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=39 has NO THING associated, ignoring it
2018-12-22 20:17:14.251 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*039##
2018-12-22 20:17:14.303 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0413##
2018-12-22 20:17:14.304 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00413##
2018-12-22 20:17:14.305 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0413 has NO THING associated, ignoring it
2018-12-22 20:17:14.362 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0315##
2018-12-22 20:17:14.362 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00315##
2018-12-22 20:17:14.363 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0315 has NO THING associated, ignoring it
2018-12-22 20:17:14.419 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0214##
2018-12-22 20:17:14.420 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00214##
2018-12-22 20:17:14.421 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0214 has NO THING associated, ignoring it
2018-12-22 20:17:14.477 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *2*0*0212##
2018-12-22 20:17:14.478 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *2*00212##
2018-12-22 20:17:14.479 [DEBUG] [bnet.handler.OpenWebNetBridgeHandler] - ==OWN==  ownId=0212 has NO THING associated, ignoring it
2018-12-22 20:17:15.476 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2018-12-22 20:17:15.777 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*033##
2018-12-22 20:17:15.778 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*033##.
2018-12-22 20:17:15.779 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*031##
2018-12-22 20:17:15.779 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*031##.
2018-12-22 20:17:15.780 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*044##
2018-12-22 20:17:15.781 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*044##.
2018-12-22 20:17:15.781 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*037##
2018-12-22 20:17:15.782 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*037##.
2018-12-22 20:17:15.782 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*035##
2018-12-22 20:17:15.783 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*035##.
2018-12-22 20:17:15.784 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*048##
2018-12-22 20:17:15.784 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*048##.
2018-12-22 20:17:15.785 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*046##
2018-12-22 20:17:15.786 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*046##.
2018-12-22 20:17:15.786 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00210##
2018-12-22 20:17:15.787 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00210##.
2018-12-22 20:17:15.788 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*028##
2018-12-22 20:17:15.788 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*028##.
2018-12-22 20:17:15.789 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00311##
2018-12-22 20:17:15.790 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00311##.
2018-12-22 20:17:15.790 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*039##
2018-12-22 20:17:15.791 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*039##.
2018-12-22 20:17:15.791 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00413##
2018-12-22 20:17:15.792 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00413##.
2018-12-22 20:17:15.793 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00315##
2018-12-22 20:17:15.793 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00315##.
2018-12-22 20:17:15.794 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00214##
2018-12-22 20:17:15.795 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00214##.
2018-12-22 20:17:15.795 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*00212##
2018-12-22 20:17:15.796 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *2*00212##.
2018-12-22 20:17:15.797 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING   ============>>>>>>>>>>>> *#1004*0*7##
2018-12-22 20:17:15.906 [INFO ] [org.openwebnet.OpenGatewayBus$a     ] - MON RECEIVED  <<<<<<<<<<< *#1001*0*7*111111111111111101101111##
2018-12-22 20:17:15.906 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#1001*0*7*111111111111111101101111##
2018-12-22 20:17:15.906 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC not supported, frame=*#1001*0*7*111111111111111101101111##
2018-12-22 20:17:15.907 [DEBUG] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111101101111##
2018-12-22 20:17:18.806 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED  <<<<<<<<<<<<============ *#*1##
2018-12-22 20:17:19.107 [DEBUG] [penwebnet.message.OpenMessageFactory] - ##openwebnet## WHO=AUTOMATION_DIAGNOSTIC not supported, frame=*#1001*0*7*111111111111111101101111##
2018-12-22 20:17:19.107 [INFO ] [org.openwebnet.OpenGatewayBus       ] - ##gw-openwebnet-BUS## IGNORING RESPONSE Malformed/Unsupported OpenMessage: *#1001*0*7*111111111111111101101111##.
2018-12-22 20:17:19.109 [INFO ] [overy.BusGatewayDiscoveryParticipant] - ================================================
=

Thanks

mvalla commented 5 years ago

something strange is going on here: messages like: 2018-12-22 20:17:12.974 [INFO ] [g.openwebnet.bus.MyHomeSocketFactory] - COM RECEIVED <<<<<<<<<<<<============ *2*033## are invalid in the OpenWebNet protocol (*2*033## is an invalid frame!!!), so is very unlikely that the MyHomeServer1 sends these messages back as answers to the binding. So something is still not showing correctly in your log.

Download this official client from BTicino: https://www.myopen-legrandgroup.com/developers/ >> tab TOOLS >> download and run OpenWebNet Client - Windows (exec file is OpenWebNet_Client.exe).
put the IP address of your MyHomeServer1 and its password (select HMAC if password has characters and not just numbers).
write *#2*0## in the Frame box, click Send

Then send here a picture of the result. bye M

Jeby057 commented 5 years ago

Here the picture of the result. capture Thanks

fmalfatto commented 5 years ago

Same for me! cattura

mvalla commented 5 years ago

Ok very interesting. So we have 2 gateways that send invalid OpenWebNet frames answers!! BTicino products are full of surprises!

Which versions of MyHOMEServer1 firmware do you have?

Can you do the same test here with this command: *#1*0## I want to see if this command can discover both lights (*1*what*where##) and automations (*2*what*where##) and if automation answers have valid frames this time...

fmalfatto commented 5 years ago

My HomeServer1 is at 2.2.11 . I have another gateway (3.5 touch) and it gives the same answer to message #20##

Here is the #10## command (missing a # in your post): cattura

EDIT: Updated to 2.2.14 just now and no changes in invalid frames. For your info, my 4 invalid frames are referring to 4 LN4672M2 controlling solar blinds.

mvalla commented 5 years ago

Ok @fmalfatto I imagine you also cannot find automatically from PaperUI inbox the automations devices in your system, right? Can you configure them manually via PaperUI?

What answer the OWN client gives to this command: *#2*031## ?

fmalfatto commented 5 years ago

I do not use PaperUi actually, but it always had found all devices. I have already all things I need and I manage them via text files and sitemap. Never had problems with discovering. I launched just now and it discovered again all the lights and thermostats (no automation at all). I never understood how paperui works, so I left soon to configure everything in text files. Every time I launch it, I need to erase a lot of new things :-( Automation things (4 * rollershutter) are configured both in PaperUI and in text files and are correctly managed bot in paperui and in basicui. I use them since 6 months without problems!

The answer to #2031## is #0##===#2*031#===

Jeby057 commented 5 years ago

Hi,

I have the last version of the firmware (2.2.14).

Here the results of #10## command : capture1 capture2

Edit : I have the same result with the command *#2*031## : *#*0##===*#2*031##===

mvalla commented 5 years ago

Sorry @fmalfatto but I cannot understand from your description if using the PaperUI Inbox scan you can find all your 4 automation devices or not. Can you post an image of the scan result from PaperUI? Also can you post here the configuration line you use in .things file for the rollershutter with WHERE=031 ??

You have the same MyHOMEServer1 gateway as @Jeby057 but he cannot find his rollershutter and you say you have found all your devices.

Also the answers both of you have from the automation discovery request (*#2*0##) are completely non-sense to me because they brake the basic formatting rules off OpenWebNet messages according to their official documentation.

Massi [IMPORTANT always use code fences when posting OWN framesotherwise the web page will modify them and cut characters form them !!]

Jeby057 commented 5 years ago

You said that the response of the gateway are non-sense. But usually what should be the answer of this request ? Maybe it's due to a recent update of Bticino. It's strange that they don't follow the official documentation. Have you an idea to do a workaround ? Thanks

fmalfatto commented 5 years ago

Sorry for my poor english. I realize I confused your ideas! I try to explain: 1) PaperUi never found the 4 automation devices. I installed them after I began to configure everything manually and never rescan devices again until today. And neither today it found automation devices. It listed all switches and thermostats also if they are all alredy defined in files.

2) things file for the first rollershutter:
bus_automation Studio_F "T. Studio F." [ where="31", shutterRun="40000" ]

3) items file: Rollershutter Tenda_St_F "T. Fulvio [%d %%]" (Tende) { channel="openwebnet:bus_automation:mhs1:Studio_F:shutter"}

With these configuration files I found things in Paper UI : cattura

fmalfatto commented 5 years ago

This is the log of the scan done by PaperUI:

2018-12-23 17:35:41.206 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:11' to inbox.
2018-12-23 17:35:41.233 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:21' to inbox.
2018-12-23 17:35:41.255 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:12' to inbox.
2018-12-23 17:35:41.276 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:22' to inbox.
2018-12-23 17:35:41.291 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:13' to inbox.
2018-12-23 17:35:41.299 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:23' to inbox.
2018-12-23 17:35:41.310 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:14' to inbox.
2018-12-23 17:35:41.319 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:24' to inbox.
2018-12-23 17:35:41.331 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:03' to inbox.
2018-12-23 17:35:41.341 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:02' to inbox.
2018-12-23 17:35:41.350 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:15' to inbox.
2018-12-23 17:35:41.359 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:25' to inbox.
2018-12-23 17:35:41.367 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:06' to inbox.
2018-12-23 17:35:41.379 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:09' to inbox.
2018-12-23 17:35:41.386 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:05' to inbox.
2018-12-23 17:35:41.397 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:08' to inbox.
2018-12-23 17:35:41.407 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:04' to inbox.
2018-12-23 17:35:41.416 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:07' to inbox.
2018-12-23 17:35:41.425 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:0010' to inbox.
2018-12-23 17:35:41.435 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_on_off_switch:mhs1:01' to inbox.
2018-12-23 17:35:43.869 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*031##
2018-12-23 17:35:43.872 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*032##
2018-12-23 17:35:43.873 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*033##
2018-12-23 17:35:43.875 [ERROR] [penwebnet.message.OpenMessageFactory] - ##openwebnet## INVALID FRAME: *2*034##
2018-12-23 17:35:50.396 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_thermostat:mhs1:1' to inbox.
2018-12-23 17:35:50.410 [INFO ] [g.discovery.internal.PersistentInbox] - Added new thing 'openwebnet:bus_thermostat:mhs1:2' to inbox.

In my opinion the server should answer *2*0*31## instead of *2*031## because I have four devices on automation bus, numbered from 31 to 34.

mvalla commented 5 years ago

You said that the response of the gateway are non-sense. But usually what should be the answer of this request ? Maybe it's due to a recent update of Bticino. It's strange that they don't follow the official documentation. Have you an idea to do a workaround ?

In my opinion the server should answer *2*0*31## instead of *2*031## because I have four devices on automation bus, numbered from 31 to 34.

Exactly: the answer to the "get status" command for Automation: *#2*0## should be a list of commands with the status for each automation device: for example: *2*0*31## for WHERE=31.

Receiving *2*031## makes no sense to me and is against the official BTicino documentation, but this happens both with the binding and the official OWN client. Which is absurd, because I have many other users with MyHOMEServer1 who had no problems with finding automation devices in their system.

So I will keep this issue open and ask more information elsewhere.

The workaround is clear but very very ugly: to accept these not valid answers from MyHOMEServer1 (maybe just for this model of gateway and for some specific firmware versions).. just to fix a ugly bug by BTicino.

Jeby057 commented 5 years ago

After some research about OpenWebNet language and some tests, I think you are right. It's very strange... Why the * is missing in the answer... Maybe a bug directly in the firmware.

Edit : I didn't see your previous message. I think that accept these answers isn't a good idea or maybe just temporarily (not a lot of choice...). On my side, I will follow up the bug to my Bticino installer.

mvalla commented 5 years ago

However I have a lot of bugs in the log file when I use it. ##openwebnet## INVALID FRAME: *2*1000#0*31## exception: java.lang.IllegalArgumentException: unsupported WHAT for frame: 1000#0

This is already reported in issue #29 , an will be supported in beta9 when released.

the actions have not effect on the shutter.

Regarding the automations working, from the logs it looks like the UP/DOWN commands sent to the BUS are confirmed. For example this Down command: *2*2*31## to automation device 31 has been confirmed going down from the BUS in the following MONitor message *2*2*31##: 21:21:40.264 [INFO ] [g.openwebnet.bus.MyHomeJavaConnector] - COM SENDING ============>>>>>>>>>>>> *2*2*31## ... 21:21:40.389 [INFO ] [org.openwebnet.OpenGatewayBus$a ] - MON RECEIVED <<<<<<<<<<< *2*2*31## 21:21:40.390 [DEBUG] [.handler.OpenWebNetAutomationHandler] - ==OWN:AutomationHandler== # 31 # MOVING 2 - startedMovingAt=1545596500390 - 40.390

So where is the problem?

Jeby057 commented 5 years ago

Ok for the release 9.

About the another problem, it's my mistake. I have a lot of shutters :-) So, if we configure manually, it works fine.

Thanks.

fmalfatto commented 5 years ago

I wrote to BTicino about the invalid frames returned from automation bus. They only answered that MHS1 is not an official gateway: Gentile cliente, in merito alla sua richiesta, siamo ad informarla che il prodotto MyHomeserver1 non viene dichiarato con la funzionalità di gateway.Pertanto tutte le funzionalità di invio/ricezione/traduzione codici OPEN non viene garantita. Per integrazioni con terze parti siamo a consigliarLe di installare il webserver art. F454 o il driver manager art. I would expect a not supported message or an error, not a wrong frame :-) I have another unsupported gw and I will try to see if it gives the same problem.

fmalfatto commented 5 years ago

I can confirm that the invalid frames received as answer to the "get status" command for Automation is a problem coming from the MHS1. I changed my gateway using the touch 3.5 LN4890 (ufficially unsupported) and the messages about wrong frames are gone. PaperUi discovers all my blinds: cattura Now I try to keep in test the LN4890 as gateway (I did not change the name for mhs1 in things file so I have the same devices as before): `// Bridge openwebnet:bus_gateway:mhs1 [ host="192.168.1.40", passwd="" ] {

// test gw touch 3.5 LN4890

Bridge openwebnet:bus_gateway:mhs1 [ host="192.168.1.50", passwd="" ] {`

mvalla commented 5 years ago

Ok since the problem is with the MHS1 gateway, this will not be fixed in the binding. I will leave this issue open to track if the problem is solved in future firmware versions.

mvalla commented 5 years ago

@fmalfatto is this issue still valid or has it been corrected by BTicino?

fmalfatto commented 5 years ago

I tested today after the new firmware on MHS1 and it looks as fixed: Cattura

mvalla commented 5 years ago

ok, than this issue can be closed

mvalla / openhab-addons

Discovering automation things with MyHomeServer1 #34