Zwave Errors

My Zwave network is working perfectly however in the log (via karaf) is see some errors happening.
I can’t figure out why this occur.

Can anyone tell me hoe to trouble shout this? Or can i ignore these messages? Thanks all

Karaf -> log:tail

18:13:34.260 [WARN ] [ocol.ZWaveController$ZWaveSendThread] - NODE 30: Too many retrie s. Discarding message: Message: class=SendData[0x13], type=Request[0x00], priority=Get , dest=30, callback=80, payload=1E 03 71 04 00
18:13:39.489 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 28: Timeout while s ending message. Requeueing - 2 attempts left!
18:13:39.491 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 28: Got an error wh ile sending data. Resending message.
18:13:44.497 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 28: Timeout while s ending message. Requeueing - 1 attempts left!
18:13:44.499 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 28: Got an error wh ile sending data. Resending message.
18:13:49.509 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 28: Timeout while s ending message. Requeueing - 0 attempts left!
18:13:49.512 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 28: Got an error wh ile sending data. Resending message.

You need to switch to debug logging …

on a karaf console type

log:set debug org.openhab.binding.zwave

the strange thing is, it is not every time the same node.

thanks for this suggestion. i see loads of messages passing by now :slight_smile:

again errors… does anyone know why ? (see debug messages below)

21:09:26.602 [WARN ] [ocol.ZWaveController$ZWaveSendThread] - NODE 3: Too many retries. Discarding message: Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=3, callback=223, payload=03 03 71 04 00
21:09:55.526 [ERROR] [apache.karaf.shell.support.ShellUtil] - Exception caught while executing command
java.lang.IllegalArgumentException: No enum constant org.apache.karaf.log.core.Level.DEBUGORG.OPENHAB.BINDING.ZWAVE
at java.lang.Enum.valueOf(Enum.java:238)[:1.8.0_121]
at org.apache.karaf.log.core.Level.valueOf(Level.java:23)[50:org.apache.karaf.log.core:4.0.8]
at org.apache.karaf.log.core.internal.LogServiceImpl.setLevel(LogServiceImpl.java:125)[50:org.apache.karaf.log.core:4.0.8]
at org.apache.karaf.log.command.SetLogLevel.execute(SetLogLevel.java:48)[50:org.apache.karaf.log.core:4.0.8]
at org.apache.karaf.shell.impl.action.command.ActionCommand.execute(ActionCommand.java:83)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:67)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.karaf.shell.impl.console.osgi.secured.SecuredCommand.execute(SecuredCommand.java:87)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.Closure.executeCmd(Closure.java:480)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.Closure.executeStatement(Closure.java:406)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.Pipe.run(Pipe.java:108)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:182)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.Closure.execute(Closure.java:119)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.felix.gogo.runtime.CommandSessionImpl.execute(CommandSessionImpl.java:94)[55:org.apache.karaf.shell.core:4.0.8]
at org.apache.karaf.shell.impl.console.ConsoleSessionImpl.run(ConsoleSessionImpl.java:274)[55:org.apache.karaf.shell.core:4.0.8]
at java.lang.Thread.run(Thread.java:745)[:1.8.0_121]

wat does this mean : 1 node generates a lot of messages ending with this line

21:17:56.078 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=17, expected=SendData, cancelled=false MISMATCH

First thing: could you please format your logs with the </> button so we can read it more easily?

21:09:55.526 [ERROR] [apache.karaf.shell.support.ShellUtil] - Exception caught while executing command
java.lang.IllegalArgumentException: No enum constant org.apache.karaf.log.core.Level.DEBUGORG.OPENHAB.BINDING.ZWAVE

That looks pretty weird, never seen that before. On which platform did you install openHAB? Which install method? Which version? Stable or snapshot?
Do you have a current java version (should be at least 101, current is even newer). Oracle Java or OpenJDK? (Oracle is recommended).

It’s a perfectly normal debug message relating to transaction management in the binding - it is not an error in any way.

thanks for the reply chris. I’m trying to sort this out :slight_smile:

Hi chris,

i found this quote from you somewhere :

There is no “node 255 issue”.**

Node 255 is what will be printed in the log when the binding is sending a message to the controller - it’s as simple as that. It’s not a problem, it identifies control commands - ie commands that aren’t sent to a ‘real’ node.**

With the example you’ve posted above, this command is a “IsFailedNode” request. It’s asking the controller if node 6 is in the controllers failed nodes list. It’s not sending anything to node 6 - it’s simply talking to the stick.**
Chris

is this related to tihs errror? Notice the Node2 Errors (now node2 is the only zwave device on the network. Besides the controller)
\

09:13:15.397 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 1 attempts left!
09:13:15.403 [WARN ] [age.RequestNetworkUpdateMessageClass] - RequestNetworkUpdate not placed on stack.
09:13:20.399 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Timeout while sending message. Requeueing - 0 attempts left!
09:13:20.406 [WARN ] [age.RequestNetworkUpdateMessageClass] - RequestNetworkUpdate not placed on stack.
09:13:25.402 [WARN ] [ocol.ZWaveController$ZWaveSendThread] - NODE 255: Too many retries. Discarding message: Message: class=RequestNetworkUpdate[0x53], type=Request[0x00], priority=High, dest=255, callback=0, payload=01
09:13:25.488 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 2: Timeout while sending message. Requeueing - 0 attempts left!
09:13:25.490 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 2: Got an error while sending data. Resending message.

Your error is just a retry - I can’t really say what it is - it’s a message being sent to a node, and the node might not be responding. Without debug information, it’s really hard to guess what’s happening here. Timeouts aren’t totally uncommon - if you get lots of them, then it’s either because there’s a systematic issue, or a random issue linked to (for example) RF communications which will change over time.

Thanks Chris.

i think i figured it out what was wrong : please correct me if i’m wrong
I was testing with zwave devices on openHAB. a lot of times i deleted things however , the items stayed in the list. (visible via karaf). These items were still connected (administratively) to a thing. by adding the thing again a new item was added along it. So now two items are connected to 1 thing (visible one via karaf visible 2)

this “zombie” device was cousing the errors. everything is cleaned op now and low and behold… no more errors