openHAB 2.0 binding

[Timo Engelgeer]

I'm trying to make a energy/Watt meter based on a ATM90E26 to send Watt, VAR, VA etc to OpenHab. But I'm running into two problems.

First, not all channels show up in OpenHab. Only Watt, KWH (which should be named kWh), Battery level and Last Update show up from my S_POWER sensor. VAR, VA and PowerFactor are missing. Also V_VAR1 (the custom values) are not showing.

Second problem is with decimal places. From my sensor I send a value with a decimal place. The log shows me:
11074 TSF:MSG:SEND,2-2-0-0,s=1,c=1,t=38,pt=0,l=6,sg=0,ft=0,st=OK:236.16
for a voltage of 236.16V. Only OpenHab ignores the decimal place most of the time. It just shows 236.0V. Somethings for a fraction of a second a non-zero decimal is shown.

Both is with OpenHAB 2.0.0 and MySensors Binding 2.1.0

[TimO]

Hi @Timo-Engelgeer !

1. KWH -> kWH.

2. VAR, VA & Power Factor added.

3. Decimal place: Did you check your item definition? kWH should be shown with two decimal places.

4. Is it possible for you to use the CustomSensor with var1-var5 (with a separate childId)?

Looks like this now in PaperUI:

I'll push these changes with the next release I'm working on.

[Fabien]

Since last update, I have to reinstall mysensors binding from IOT marketplace. But lot of warning when I'm starting :

14:21:30.964 [WARN ] [.smarthome.config.core.Configuration] - Could not set field value for field 'nodeId': Can not set int field org.openhab.binding.mysensors.config.MySensorsSensorConfiguration.nodeId to java.lang.String
java.lang.IllegalArgumentException: Can not set int field org.openhab.binding.mysensors.config.MySensorsSensorConfiguration.nodeId to java.lang.String
	at sun.reflect.UnsafeFieldAccessorImpl.throwSetIllegalArgumentException(UnsafeFieldAccessorImpl.java:167)[:1.8.0_131]
	at sun.reflect.UnsafeFieldAccessorImpl.throwSetIllegalArgumentException(UnsafeFieldAccessorImpl.java:171)[:1.8.0_131]
	at sun.reflect.UnsafeIntegerFieldAccessorImpl.set(UnsafeIntegerFieldAccessorImpl.java:98)[:1.8.0_131]
	at java.lang.reflect.Field.set(Field.java:764)[:1.8.0_131]
	at org.apache.commons.lang.reflect.FieldUtils.writeField(FieldUtils.java:523)[30:org.apache.commons.lang:2.6.0]
	at org.apache.commons.lang.reflect.FieldUtils.writeField(FieldUtils.java:500)[30:org.apache.commons.lang:2.6.0]
	at org.apache.commons.lang.reflect.FieldUtils.writeField(FieldUtils.java:560)[30:org.apache.commons.lang:2.6.0]
	at org.eclipse.smarthome.config.core.Configuration.as(Configuration.java:96)[94:org.eclipse.smarthome.config.core:0.9.0.201706270841]
	at org.eclipse.smarthome.core.thing.binding.BaseThingHandler.getConfigAs(BaseThingHandler.java:248)[105:org.eclipse.smarthome.core.thing:0.9.0.201706270841]
...

And now I can't control my shutters :

14:22:11.672 [WARN ] [ensors.handler.MySensorsThingHandler] - Variable not found, cannot handle command for thing mysensors:cover:ESPgw:VoletBureau of type cover
14:22:11.674 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'VoletBureau' received command UP
14:22:11.682 [INFO ] [marthome.event.ItemStateChangedEvent] - VoletBureau changed from NULL to 0
14:22:13.198 [WARN ] [ensors.handler.MySensorsThingHandler] - Variable not found, cannot handle command for thing mysensors:cover:ESPgw:VoletBureau of type cover
14:22:13.200 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'VoletBureau' received command STOP
14:22:14.063 [WARN ] [ensors.handler.MySensorsThingHandler] - Variable not found, cannot handle command for thing mysensors:cover:ESPgw:VoletBureau of type cover
14:22:14.064 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'VoletBureau' received command UP
14:22:17.362 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 211;0;1;0;52;464.1

bundle info :

223 | Active   |  80 | 2.2.0.201707181152     | MySensors Binding

[TimO]

Hey @Fabien !

I've switched to a new release please look here for further information: https://forum.mysensors.org/topic/7176/openhab-2-2-binding

I've added a download link to the old version too in case you won't have time to change your configuration. ;-)

[Fabien]

Thann you @TimO. I will try it.

[Timo Engelgeer]

@TimO Thanks for your answers!

1. The symbol for hour really is a small h ;) https://en.wikipedia.org/wiki/Kilowatt_hour
2. Great! And you'r right, it's indeed var, not VAR (weirdly enough...) Right on time for the demo :package:
3. I've lost where I can check that :no_mouth: But weird thing is, OpenHAB does show it with decimals, they're just zero most of the time...
4. Could do, I just used it with S_POWER because the MySensors doc said they should work with any device. Which I think does make sense in order not to cluther things with S_CUSTOMS. But then again, S_POWER having a V_VOLTAGE also makes sense :p

[TimO]

@Timo-Engelgeer

1. Yes, of course! It's changed by now.

2. Did you define something in the *.items file? "%d" instead of "%.2f" ?

3. It is very easy for me to add all var1-var5 channels to all things. It's just a litte copy & paste. The reason I've not done this so far is, that all channels are displayed and configured in the control section of the PaperUI (in simple mode). So if you've a temperature sensor, you additionally get: battery, lastUpdate, var1, var2, var3, var4 and var5. All of them can be disabled easily though.

[Timo Engelgeer]

Heyy @TimO (are you really called the same as me?) Thanks for getting back to me!

1. Thanks!
2. Nope, for now I just used the simple mode so no .items file.
3. Yeah, I agree it can make it feel a bit more cluttered. But then again, battery is also not used in every device (and useless in wall powered sensors). Also other channels you might not use them all as well. For example, I use S_MULTIMETER for voltage and current (because weirdly S_POWER lacks that) but impedance is an useless/meaningless channel here.

[Feji]

This post is deleted!

[seeers]

Hi, I use OpenHAB 2.1 with the OpenHAB 2.0 Binding on a Raspberry Pi with a serial RS485 gateway connected with a FTDI to USB. Sometimes the gateway freezes (at the moment i don´t know why). After pushing the reset button everything works for the next hours/days.
I´d like to reset the gateway automatically from openhab. Is it possible to reset the Gateway with this binding ? (Send DTR to the FTDI)

[TimO]

Hi @seeers !

Interesting idea. I suppose the arduino IDE is doing this via avrdude and we won't be able to call avrdude from within the binding. If someone has an idea on how to reset an arduino via serial/usb from within java I could look into it.

[gonzalonal]

@TimO you just need to toggle the DTR line.

Try:
serialPort.setDTR(true);
Thread.sleep(500);
serialPort.open();
serialPort.setDTR(false);

[TimO]

@gonzalonal Thanks for the hint!
For that a switch of NRSerial library to SerialPort is neccessary. A first try looks promising, but I'm not able to test it against hardware yet. Maybe @seeers might give it a try:

JAR-File

I've tested with the following bridge configuration:

Bridge mysensors:bridge-ser:gateway [ serialPort="/dev/pts/20", hardReset=false, sendDelay=200, startupCheckEnabled=true, networkSanCheckEnabled=true, networkSanCheckInterval=1, networkSanCheckConnectionFailAttempts=1 ] {
  }

The sanity check needs to fail two times. After that it will try to disconnect (and do the hardReset via DTR) and reconnect.

Log (in debug) should look like:

[DEBUG] [.p.s.MySensorsSerialConnection:138  ] - Trying to reset of attached gateway with DTR
[DEBUG] [.p.s.MySensorsSerialConnection:146  ] - Finished reset of attached gateway with DTR

I suppose only FTDI based gateways will react on that? What about sensebender gateway?

[seeers]

Thanks for the suggestions and the change. Maybe it`s not necessary. Today I could observe how the gateway was rebooted by openhab by Shutting down the serial connection.
I have added a "heartbeat" to my gateway, every minute a Serial.writeline "State:OK". Of course an exception appears in the openhab binding... I do not understand why the binding was down: ...Shutting down serial connection!...

11:29:43.176 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: State:OK
11:29:43.177 [WARN ] [rsAbstractConnection$MySensorsReader] - Exception on reading from connection
java.text.ParseException: class java.text.ParseException : Message length is not > 4   .....

11:29:43.181 [DEBUG] [col.serial.MySensorsSerialConnection] - Request disconnection flag setted to: true
11:29:44.873 [INFO ] [col.serial.MySensorsSerialConnection] - Connection request disconnection...
11:29:44.874 [DEBUG] [col.serial.MySensorsSerialConnection] - Request disconnection flag setted to: false
11:29:44.874 [DEBUG] [col.serial.MySensorsSerialConnection] - Shutting down serial connection!
11:29:44.875 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Stopping Writer thread
11:29:44.876 [WARN ] [rsAbstractConnection$MySensorsWriter] - Interrupted MySensorsWriter
11:29:44.877 [DEBUG] [rsAbstractConnection$MySensorsReader] - Stopping Reader thread
11:29:44.882 [WARN ] [rsAbstractConnection$MySensorsReader] - Interrupted MySensorsReader
.....
11:29:44.901 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:temperature:gateway:Temp_OG_KSued_2' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)
11:29:44.905 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:humidity:gateway:Hum__OG_KSued' changed from ONLINE to OFFLINE (COMMUNICATION_ERROR)
.....
11:29:48.132 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;14;Gateway startup complete.
11:29:48.132 [INFO ] [rs.internal.gateway.MySensorsGateway] - Node 0 available again!
11:29:48.133 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;0;0;18;2.1.1
11:29:48.133 [WARN ] [col.serial.MySensorsSerialConnection] - Skipping I_VERSION connection test, not recommended...
11:29:48.137 [DEBUG] [ensors.factory.MySensorsCacheFactory] - Writing on cache given_ids, content: [0,32,1,33,34,35,11,12,13,14,21,22,23,24,26,27,31]
11:29:48.138 [INFO ] [col.serial.MySensorsSerialConnection] - Successfully connected to MySensors Bridge.
11:29:48.138 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Presentation Message received
11:29:48.138 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:temperature:gateway:Temp_KG_Flur_1
....
11:29:48.140 [DEBUG] [ensors.handler.MySensorsThingHandler] - MySensors Bridge Status updated to ONLINE for device: mysensors:humidity:gateway:Hum__KG_Wasch
11:29:48.142 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'mysensors:bridge-ser:gateway' changed from OFFLINE to ONLINE
....

[stolly82]

This post is deleted!

[stolly82]

Dear together,

I can´t find out, why one things are not working if I save another thing with the same NodeID but different ChildID.

Example:
I have two things, one button and one door sensor.

The button is working until I add a new thing with the same nodeID (Relay).

If I save the button again, it´s working again but the Relay isn´t working again.

21:07:58.514 [INFO ] [smarthome.event.ThingUpdatedEvent   ] - Thing 'mysensors:light:Relais_Wohnzimmer_hinten_1' has been updated.
21:07:58.518 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Adding device MySensorsNode [nodeId=16, childNumber=1, chidldList={1=MySensorsChild [childId=1, nodeValue={V_WATT=MySensorsVariableVWatt [value=null], V_STATUS=MySensorsVariableVStatus [value=null], V_VAR2=MySensorsVariableVVar2 [value=null], V_VAR1=MySensorsVariableVVar1 [value=null], V_VAR3=MySensorsVariableVVar3 [value=null], V_VAR4=MySensorsVariableVVar4 [value=null], V_VAR5=MySensorsVariableVVar5 [value=null]}]}]
21:07:58.520 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 16-1 not registered yet, registering...
21:08:00.332 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:00.337 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:00.347 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:00.352 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:00.370 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:00.000+0200
21:08:10.860 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;0
21:08:10.865 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:10.869 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:10.944 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;1
21:08:10.949 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:10.952 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:11.012 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:11.016 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:11.019 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:11.022 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:11.050 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:11.000+0200
21:08:11.241 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;1
21:08:11.247 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:11.251 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 16
21:08:11.254 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: ON
21:08:11.271 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:11.000+0200
21:08:11.275 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: ON
21:08:11.292 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_1 changed from OFF to ON
21:08:11.295 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:11.000+0200
21:08:12.196 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;0
21:08:12.199 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:12.201 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 16
21:08:12.204 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: OFF
21:08:12.219 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:12.000+0200
21:08:12.222 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: OFF
21:08:12.239 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_1 changed from ON to OFF
21:08:12.242 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:12.000+0200
21:08:14.093 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;1
21:08:14.097 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:14.099 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 16
21:08:14.101 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: ON
21:08:14.118 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:14.000+0200
21:08:14.121 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: ON
21:08:14.137 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_1 changed from OFF to ON
21:08:14.147 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:14.000+0200
21:08:14.574 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;0
21:08:14.578 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:14.581 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 16
21:08:14.584 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: OFF
21:08:14.603 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:14.000+0200
21:08:14.607 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: status(V_STATUS) value to: OFF
21:08:14.628 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_1 changed from ON to OFF
21:08:14.632 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/1 to 2017-09-09T21:08:14.000+0200
21:08:15.823 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.1.1
21:08:20.282 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:20.286 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:20.290 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:20.293 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:20.319 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:20.000+0200
21:08:21.117 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;0
21:08:21.122 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:21.126 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:21.232 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;1
21:08:21.237 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:21.243 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:22.162 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;0
21:08:22.168 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:22.174 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:22.592 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;1
21:08:22.596 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:22.599 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:23.331 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;0
21:08:23.334 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:23.337 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:23.491 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;1
21:08:23.495 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:08:23.506 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 16
21:08:30.411 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:30.414 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:30.417 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:30.421 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:30.447 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:30.000+0200
21:08:40.313 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:40.317 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:40.326 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:40.330 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:40.352 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:40.000+0200
21:08:50.328 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:08:50.332 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:08:50.337 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:08:50.342 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:08:50.367 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:08:50.000+0200
21:08:55.944 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;18;4653.2749
21:08:55.950 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:08:55.954 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:08:55.959 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: kwh(V_KWH) value to: 4653.2749
21:08:55.978 [INFO ] [marthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 4653.1982 to 4653.2749
21:08:55.983 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:08:55.000+0200
21:09:00.403 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:09:00.409 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:09:00.413 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:09:00.418 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:09:00.449 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:09:00.000+0200
21:09:10.370 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:09:10.375 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:09:10.378 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:09:10.382 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:09:10.411 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:09:10.000+0200
21:09:15.971 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;24;6979929
21:09:15.974 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:09:15.977 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:09:15.980 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: var1(V_VAR1) value to: 6979929
21:09:15.998 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:09:15.000+0200
21:09:16.193 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;18;4653.2866
21:09:16.196 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:09:16.198 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:09:16.203 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: kwh(V_KWH) value to: 4653.2866
21:09:16.221 [INFO ] [marthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 4653.2749 to 4653.2866
21:09:16.233 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:09:16.000+0200
21:09:16.296 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.1.1
21:09:20.390 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:09:20.394 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:09:20.398 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:09:20.402 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:09:20.424 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:09:20.000+0200
21:09:30.464 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:09:30.470 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:09:30.475 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:09:30.481 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:09:30.510 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:09:30.000+0200
21:09:40.469 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:09:40.473 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:09:40.477 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:09:40.482 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:09:40.505 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:09:40.000+0200
21:10:00.627 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:00.629 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:00.634 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:00.640 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:00.666 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:00.000+0200
21:10:10.690 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:10.696 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:10.701 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:10.707 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:10.749 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:10.000+0200
21:10:15.893 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 0;255;3;0;2;2.1.1
21:10:17.669 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;18;4653.3081
21:10:17.673 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:10:17.675 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:10:17.678 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: kwh(V_KWH) value to: 4653.3081
21:10:17.702 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:10:17.000+0200
21:10:17.703 [INFO ] [marthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 4653.2866 to 4653.3081
21:10:20.537 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:20.541 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:20.546 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:20.551 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:20.590 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:20.000+0200
21:10:30.566 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:30.569 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:30.576 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:30.582 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:30.602 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:30.000+0200
21:10:34.691 [DEBUG] [ensors.handler.MySensorsThingHandler] - Configuation update for thing 16-2: {nodeUpdateTimeout=-1, smartSleep=false, requestHeartbeatResponse=false, childUpdateTimeout=-1, childId=2, nodeId=16, revertState=true, requestAck=false}
21:10:34.727 [INFO ] [smarthome.event.ThingUpdatedEvent   ] - Thing 'mysensors:door:Button_Wohnzimmer_1' has been updated.
21:10:34.726 [DEBUG] [ensors.handler.MySensorsThingHandler] - Configuration: MySensorsSensorConfiguration [nodeId=16, childId=2, requestAck=false, revertState=true, smartSleep=false, childUpdateTimeout=-1, nodeUpdateTimeout=-1, requestHeartbeatResponse=false]
21:10:34.740 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Adding device MySensorsNode [nodeId=16, childNumber=1, chidldList={2=MySensorsChild [childId=2, nodeValue={V_VAR2=MySensorsVariableVVar2 [value=null], V_VAR1=MySensorsVariableVVar1 [value=null], V_TRIPPED=MySensorsVariableVTripped [value=null], V_VAR3=MySensorsVariableVVar3 [value=null], V_VAR4=MySensorsVariableVVar4 [value=null], V_VAR5=MySensorsVariableVVar5 [value=null], V_ARMED=MySensorsVariableVArmed [value=null]}]}]
21:10:34.752 [DEBUG] [ensors.handler.MySensorsThingHandler] - Event listener for node 16-2 not registered yet, registering...
21:10:37.699 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;24;6979975
21:10:37.704 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:10:37.709 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:10:37.714 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: var1(V_VAR1) value to: 6979975
21:10:37.734 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:10:37.000+0200
21:10:37.821 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 1;5;1;0;18;4653.3169
21:10:37.827 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 1 found in gateway
21:10:37.833 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 5 found in node 1
21:10:37.839 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: kwh(V_KWH) value to: 4653.3169
21:10:37.859 [INFO ] [marthome.event.ItemStateChangedEvent] - Stromzaeler_K changed from 4653.3081 to 4653.3169
21:10:37.875 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 1/5 to 2017-09-09T21:10:37.000+0200
21:10:40.611 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:40.616 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:40.621 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:40.626 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:40.647 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:40.000+0200
21:10:46.592 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;1
21:10:46.598 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:10:46.602 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 not present in node 16
21:10:48.047 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;1;1;0;2;0
21:10:48.052 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:10:48.056 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 not present in node 16
21:10:50.179 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;0
21:10:50.184 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:10:50.186 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 16
21:10:50.188 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: CLOSED
21:10:50.200 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_2 changed from OPEN to CLOSED
21:10:50.204 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/2 to 2017-09-09T21:10:50.000+0200
21:10:50.387 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 16;2;1;0;16;1
21:10:50.389 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 16 found in gateway
21:10:50.391 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 16
21:10:50.393 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:50.413 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Wohnzimmer_hinten_2 changed from CLOSED to OPEN
21:10:50.422 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 16/2 to 2017-09-09T21:10:50.000+0200
21:10:50.616 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:10:50.619 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:10:50.621 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:10:50.623 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:10:50.665 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:10:50.000+0200
21:11:00.858 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:11:00.865 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:11:00.869 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 found in node 14
21:11:00.871 [DEBUG] [ensors.handler.MySensorsThingHandler] - Updating channel: tripped(V_TRIPPED) value to: OPEN
21:11:00.889 [DEBUG] [ensors.handler.MySensorsThingHandler] - Setting last update for node/child 14/2 to 2017-09-09T21:11:00.000+0200

And it´s really with any type of things like that. All nodes are working perfect in OH2 if I have only one childID as a thing. If I add the second the first is not longer working.

21:37:34.353 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:37:34.358 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:37:34.363 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 14
21:37:44.381 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:37:44.386 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:37:44.390 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 14
21:37:54.400 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:37:54.405 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:37:54.409 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 14
21:38:04.417 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:38:04.419 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:38:04.425 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 14
21:38:10.730 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:38:10.738 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 14
21:38:10.738 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Aussenstrahler_hinten changed from OFF to ON
21:38:10.742 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 14;1;1;0;2;1
21:38:10.744 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'Switch_Aussenstrahler_hinten' received command ON
21:38:13.428 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:38:13.430 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 1 found in node 14
21:38:13.432 [INFO ] [smarthome.event.ItemCommandEvent    ] - Item 'Switch_Aussenstrahler_hinten' received command OFF
21:38:13.437 [INFO ] [marthome.event.ItemStateChangedEvent] - Switch_Aussenstrahler_hinten changed from ON to OFF
21:38:13.437 [DEBUG] [rsAbstractConnection$MySensorsWriter] - Sending to MySensors: 14;1;1;0;2;0
21:38:14.454 [DEBUG] [rsAbstractConnection$MySensorsReader] - Message from gateway received: 14;2;1;0;16;1
21:38:14.457 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Node 14 found in gateway
21:38:14.459 [DEBUG] [rs.internal.gateway.MySensorsGateway] - Child 2 not present in node 14

[matt shepherd]

Have you tried using Mqtt? I've recently set up a motion sensor with a mqtt gateway and it seems to be more reliable. The motion just sends a 1 for motion or 0 for no motion (obvs). I have a rule that says turn lights on if motion state =1 .You don't have to worry about connecting 'things' with openhab as you use the mqtt binding.

[stolly82]

@matt-shepherd
for now not.
The problem is more, that openhab 2 accepts only one device per Node with the 2.0 binding. It doesn´t matter if it it a motion sensor or if it is a second relay.

Alway´s if I setup a second thing with a existing ChildID,... the first Thing is not working anymore (LOG = Child x not present in Node x ).

I tried to install on a second Raspberry with OpenHab2 and MySensors2.0 binding (Openhabian) and there is the same issue.

That´s really really strange.

[mick]

I would like to attempt the irrigation controller with this binding, I believe the "light" thing is used. This only has channels: Status, Battery, LastUpdate. Is it possible to add more channels to the light thing ?, ie: V_VAR1 ... V_VAR3, regards.

[andreacioni]

@mick you aren't the first asking that :sweat_smile: . I opened a new issue on GitHub (https://github.com/tobof/openhab2-addons/issues/95) to discuss about adding it to the binding.