OH2 Z-Wave refactoring and testing... and SECURITY

shadowmite · May 16, 2017, 6:26am

Honestly? A lot, maybe 10 tries before I got it to work as expected. To me the secure inclusion seems a little buggy.

vossivossi · May 16, 2017, 9:28am

@chris:
The latest version fo the dev branch is throwing tons of Java exceptions in karaf console. I am using version 2.1.0.201705152248 .

java.lang.reflect.InvocationTargetException
        at sun.reflect.GeneratedMethodAccessor66.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveComman
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 6
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveComman
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfig
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.jav
:97)
        ... 6 more

However, I cannot find any details for this in the logs (although I set debug level for Z-Wave binding).

chris · May 16, 2017, 10:03am

Please provide somethign to support this (ie logs) - otherwise it can’t be fixed if there are problems.

The only issues that I have seen in logs are where devices haven’t been reset and therefore do not respond to the secure inclusion. A device can ONLY be securely included with 15 seconds of the completion of the network inclusion, so if a device has previously been included, it must be excluded first.

For me at least, I’ve not see any problems with the secure inclusion on devices I’ve include (my Yale lock - which I’ve include many many times) and a number of other device (switches, multi-sensors…).

If you have logs showing problems, then please feel free to provide them though - it’s the only way to fix them ;).

chris · May 16, 2017, 10:06am

You need to correlate the time of the exception with the received message logs. This is an array out of bounds exception so it probably means that a message was received from a device which has an error. I really need to see this message to debug.

vossivossi · May 16, 2017, 12:55pm

Where can I see the time of the exception? In Karaf console there is no timestamp with the exception message. So if I look from time to time in the console I only see that there was a new exception in the last hour or so. Is there any possibility to trace these exceptions with a timestamp?

chris · May 16, 2017, 3:02pm

Normally it should be logged in the logfile and it should then have a date/time logged.

vossivossi · May 16, 2017, 3:34pm

No, unfortunately the exceptions are only displayed in Karaf console:

java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 7 more
java.lang.reflect.InvocationTargetException
        at sun.reflect.GeneratedMethodAccessor114.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
        at java.lang.reflect.Method.invoke(Unknown Source)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.handleApplicationCommandRequest(ZWaveCommandClass.java:245)
        at org.openhab.binding.zwave.internal.protocol.ZWaveNode.processCommand(
ZWaveNode.java:1284)
        at org.openhab.binding.zwave.internal.protocol.ZWaveTransactionManager$Z
WaveReceiveThread.run(ZWaveTransactionManager.java:413)
Caused by: java.lang.ArrayIndexOutOfBoundsException: 5
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveCommand
Class.extractValue(ZWaveCommandClass.java:362)
        at org.openhab.binding.zwave.internal.protocol.commandclass.ZWaveConfigu
rationCommandClass.handleConfigurationReport(ZWaveConfigurationCommandClass.java
:97)
        ... 6 more

There is no entry in the logs with the exception (only the usual stuff).
In this case I know it was between 15:41 and 16:10 this afternoon. I looked at more than 140.000 lines of debug log entries, but the only errors were some timouts…no java exception…

chris · May 16, 2017, 3:36pm

Ok, then you need to try and correlate this another way - keep an eye out for the message that is incoming at the time of the exception. If it’s happening often then it should be reasonably easy to see?

vossivossi · May 16, 2017, 3:40pm

So far I do not understand when it happens or what the trigger is. There was no exception for more than 4 hours and then suddenly between 15:41 and 16:11 there come a couple of them.
My problem is that I do not know the time when it exactly happens. As I get nearly 100 lines of debug logs per second it is not useful to scan 30 minutes of debug logs. The only errors were timeouts and the only warnings some not initialized nodes.

vossivossi · May 16, 2017, 9:25pm

I spent quite some time this evening to get it. When I restarted my system the exceptions occured soon after restart. I then stopped OH and took the log of the last 60 seconds before the exceptions.
Unfortunately the log is way too big to post it here (although it couvers only the last minute). Can I send you the file?

dbadia · May 17, 2017, 12:22am

@chris Finally got some time to play around with OH2 and the refactored binding - the OH2 changes look really nice, much easier to include zwave devices, especially secure ones.

One issue I had with my kwikset 910. It took a couple of tried to get secure inclusion working (think it was too far away my first few attempts), then it worked and the lock appeared in the “plug” icon where I added it to the system.

Next day, I added anther (non-secure device), but the lock was back up in the plug icon again. I re-added it again, but now when I try to add a channel, the “Name” field is empty (instead of the usual zwave_device_15bfa0704b9_node#_description text)

I have the logs but not my timestamps. Wondering if anyone has had this issue. Guessing I should just exclude and re include.

For what it’s worth, my other devices are working well.

Dave

Mike_Bagdanoff · May 17, 2017, 1:29pm

Chris, for the next build could you possibly comment out the node x is currently marked failed. My logs are almost nothing but those messages every 30s, even at INFO level and as far as I can tell they are unnecessary since all of those nodes still communicate and work as normal.

2017-05-17 06:23:31.236 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 49: Is currently marked as failed by the controller!
2017-05-17 06:23:31.260 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 31: Is currently marked as failed by the controller!
2017-05-17 06:23:31.265 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 50: Is currently marked as failed by the controller!
2017-05-17 06:24:01.241 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 49: Is currently marked as failed by the controller!
2017-05-17 06:24:01.264 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 31: Is currently marked as failed by the controller!
2017-05-17 06:24:01.270 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 50: Is currently marked as failed by the controller!
2017-05-17 06:24:31.245 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 49: Is currently marked as failed by the controller!
2017-05-17 06:24:31.268 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 31: Is currently marked as failed by the controller!
2017-05-17 06:24:31.275 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 50: Is currently marked as failed by the controller!
2017-05-17 06:24:32.366 [INFO ] [.smarthome.model.script.Illumination] - Motion Detected, turning on SwitchMasterBath_Dimmer
2017-05-17 06:24:41.072 [INFO ] [.smarthome.model.script.Illumination] - Motion Detected, turning on SwitchStairs_Dimmer
2017-05-17 06:24:41.074 [INFO ] [.smarthome.model.script.Illumination] - Motion Detected, turning on OutletStairs_Switch
2017-05-17 06:25:01.248 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 49: Is currently marked as failed by the controller!
2017-05-17 06:25:01.273 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 31: Is currently marked as failed by the controller!
2017-05-17 06:25:01.281 [WARN ] [rialmessage.IsFailedNodeMessageClass] - NODE 50: Is currently marked as failed by the controller!

chris · May 17, 2017, 1:32pm

You can always just type in the item name?

If HABmin doesn’t supply a default name, this almost certainly means that an item with the default name already exists. Since the item exists, HABmin doesn’t offer a default as it assumes that if you’re adding a second item to this channel, then you need to define the name yourself.

chris · May 17, 2017, 1:34pm

At some point I will reduce the logging, but at the moment I prefer to keep the logging as it is - this is a test binding after all, so I prefer to keep the ‘heavy’ logging ;).

I will change this to debug though, but I assume most people running the test binding have debug enabled so that they can provide feedback and logs, so this won’t impact anything.

I hope that makes sense.

Mike_Bagdanoff · May 18, 2017, 1:47pm

Upgraded to:
231 | Active | 80 | 2.1.0.201705152248 | ZWave Binding

A few days ago and some sensors are now showing significant delays. One motion sensor now only registers several minutes after you leave the room and light switches and dimmers don’t seem to update status immediately in OH2 anymore.

I’ll try to get some logs this weekend

chris · May 18, 2017, 4:36pm

What did you upgrade from?

Mike_Bagdanoff · May 19, 2017, 12:34am

It was the 424 version of the .jar

chris · May 19, 2017, 6:19am

Ok, there have been very few changes between these versions - it’s mostly database updates. I think there was one small code change related to the multilevel switch converter.

Anyway, let’s take a look at the logs and we’ll see…

TheKorn · May 19, 2017, 8:00am

So may be a dumb question, but excluding secure devices shouldn’t be any different than excluding non-secure devices, right? (i.e. if it consistently fails, I should open an issue, right?)

chris · May 19, 2017, 8:28am

It should be the same. I’m not sure there will be much that I can do if it’s failing - excluding a device is a case of sending a single command to the controller… There’s one thing I’m thinking of doing is to stop all other traffic when inclusion/exclusion takes place - maybe this is related to your problem, so feel free to provide a log.If it’s not that though, then there’s probably not much I can do…