Testing Z-Wave binding on openHAB-2

Does this mean that your item is being set to these values? Or not?

"
well if understand correct what you mean and check via items command on oh2 shell

zwave_nodon_crc3100_00_000_15348538564_node13 (Type=GroupItem, Members=2, State=NULL, Label=Node 13:     CRC-3100 Octon Remote Control, Category=null, Tags=[thing])
    zwave_nodon_crc3100_00_000_15348538564_node13_battery_level (Type=NumberItem, State=NULL, Label=Battery Level, Category=Battery, Groups=[zwave_nodon_crc3100_00_000_15348538564_node13])
    zwave_nodon_crc3100_00_000_15348538564_node13_scene_number (Type=NumberItem, State=NULL, Label=Scene Number, Category=, Groups=[zwave_nodon_crc3100_00_000_15348538564_node13])

i would say no … as state is always NULL

if you meant something else … let me know

Ok - thanks - that’s what I meant… I’ll take a look when I get a chance…

Hi @chris,

the binary sensor of my Fibaro works the whole day with the actual configuration, but now it stops working and i have the following entry in the log:

22:09:34.992 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 10: No converter set for zwave:fibaro_fgms001_00_000:f1ab5187:node10:sensor_binary

I’ll tried to delete the channel via HABmin and add it new, but it don’t fix the problem.

Do you have an idea how to fix ist?

Maybe i should think about why the first letter of the messages in the log is lost, but i see in the other logs people have the same problem:wink: @Kai - what is the best way to report this, forum or Issue at Github and which repository?

Regards
Heiko

What is “this” exactly?

Maybe i realized it for the 1st time after using OH for 3 years that the log is truncated :wink:

 [ding.zwave.handler.ZWaveThingHandler]
 [marthome.event.ItemStateChangedEvent]
 [ome.event.GroupItemStateChangedEvent]

After getting this setup correctly yesterday, I have a new problem: after restarting openhab this morning, my one z-wave device, which is configured and partially working, is showing up in my inbox again as an unknown device. That is, it’s still in Things as zwave:aeon_dsc06_00_000:e2e1b51e:node5, but it’s also in inbox as wave:unknown:e2e1b51e:node5 That’s in PaperUI. In HABmin, it’s not showing up under things, and the corresponding items are not showing up under items

It partially works - I can turn it on and off via a switch item, and a rule I have to turn it back off ten minutes after being turned on works, but the power meter I have tied to a number item is showing n/a, and a cron-based rule to turn it on at various times is not firing.

looking at the log, it looks like the binding tried to talk to the devices before opening the serial port, and got confused maybe?

2016-03-11 17:50:06.035 [INFO ] [ap.impl.jmdns.JmdnsHomekitAdvertiser] - Stopping advertising in response to shutdown.
2016-03-11 17:51:47.890 [ERROR] [org.eclipse.smarthome.core.transform] - [org.eclipse.smarthome.action.transformation.action(56)] Circular reference detected, getService returning null
2016-03-11 17:52:44.360 [INFO ] [smarthome.event.ThingAddedEvent     ] - Thing 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' has been added.
2016-03-11 17:52:44.449 [INFO ] [smarthome.event.ThingAddedEvent     ] - Thing 'zwave:serial_zstick:e2e1b51e' has been added.
2016-03-11 17:52:44.860 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'CatFeeder5-zwave:aeon_dsc06_00_000:e2e1b51e:node5:switch_binary' has been added.
2016-03-11 17:52:44.898 [INFO ] [home.event.ItemChannelLinkAddedEvent] - Link 'CatFeeder5_Power-zwave:aeon_dsc06_00_000:e2e1b51e:node5:meter_watts' has been added.
2016-03-11 17:52:45.134 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'zwave_aeon_dsc06_00_000_e2e1b51e_node5-zwave:aeon_dsc06_00_000:e2e1b51e:node5' has been added.
2016-03-11 17:52:45.163 [INFO ] [rthome.event.ItemThingLinkAddedEvent] - Link 'zwave_serial_zstick_e2e1b51e-zwave:serial_zstick:e2e1b51e' has been added.
2016-03-11 17:52:45.806 [INFO ] [smarthome.event.ItemAddedEvent      ] - Item 'home_group_1d2ba494' has been added.
2016-03-11 17:52:45.853 [INFO ] [smarthome.event.ItemAddedEvent      ] - Item 'zwave_aeon_dsc06_00_000_e2e1b51e_node5' has been added.
2016-03-11 17:52:45.865 [INFO ] [smarthome.event.ItemAddedEvent      ] - Item 'zwave_serial_zstick_e2e1b51e' has been added.
2016-03-11 17:53:17.585 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from UNINITIALIZED to INITIALIZING
2016-03-11 17:53:17.976 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:serial_zstick:e2e1b51e' changed from UNINITIALIZED to INITIALIZING
2016-03-11 17:53:18.067 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:serial_zstick:e2e1b51e' changed from INITIALIZING to ONLINE
2016-03-11 17:53:18.166 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from INITIALIZING to OFFLINE
2016-03-11 17:53:18.311 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller failed to register event handler.
2016-03-11 17:53:18.314 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Connecting to serial port '/dev/ttyACM0'
2016-03-11 17:53:18.313 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller failed to register event handler.
2016-03-11 17:53:18.682 [WARN ] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Controller failed to register event handler.
2016-03-11 17:53:21.048 [INFO ] [ing.zwave.handler.ZWaveSerialHandler] - Serial port is initialized
2016-03-11 17:53:21.857 [INFO ] [ve.internal.protocol.ZWaveController] - Starting ZWave controller
2016-03-11 17:53:21.901 [INFO ] [ve.internal.protocol.ZWaveController] - ZWave timeout is set to 5000ms. Soft reset is false.
2016-03-11 17:53:32.340 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 1: Node found
2016-03-11 17:53:32.381 [INFO ] [age.SerialApiGetInitDataMessageClass] - NODE 5: Node found
2016-03-11 17:53:32.419 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller using Controller API
2016-03-11 17:53:32.448 [INFO ] [age.SerialApiGetInitDataMessageClass] - ZWave Controller is Primary Controller
2016-03-11 17:53:32.501 [INFO ] [age.SerialApiGetInitDataMessageClass] - ------------Number of Nodes Found Registered to ZWave Controller------------
2016-03-11 17:53:32.524 [INFO ] [age.SerialApiGetInitDataMessageClass] - # Nodes = 2
2016-03-11 17:53:32.559 [INFO ] [age.SerialApiGetInitDataMessageClass] - ----------------------------------------------------------------------------
2016-03-11 17:53:44.332 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 5: Device could not be resolved to a thingType! 0086:0003:0006::1.43
2016-03-11 17:53:45.828 [WARN ] [zwave.handler.ZWaveControllerHandler] - 
2016-03-11 17:53:46.714 [INFO ] [smarthome.event.InboxUpdatedEvent   ] - Discovery Result with UID 'zwave:unknown:e2e1b51e:node5' has been updated.
2016-03-11 17:53:46.853 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from OFFLINE to ONLINE: PROTOINFO
2016-03-11 17:53:48.078 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: PROTOINFO to ONLINE: NEIGHBORS
2016-03-11 17:53:49.073 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: NEIGHBORS to ONLINE: FAILED_CHECK
2016-03-11 17:53:51.873 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: FAILED_CHECK to ONLINE: WAIT
2016-03-11 17:53:51.983 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: WAIT to ONLINE: PING
2016-03-11 17:53:52.703 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: PING to ONLINE
2016-03-11 17:53:57.309 [ERROR] [ocol.ZWaveController$ZWaveSendThread] - NODE 5: Timeout while sending message. Requeueing - 0 attempts left!
2016-03-11 17:53:57.323 [ERROR] [l.serialmessage.SendDataMessageClass] - NODE 5: Got an error while sending data. Resending message.
2016-03-11 17:53:57.643 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE to ONLINE: DETAILS
2016-03-11 17:53:57.729 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: DETAILS to ONLINE: DYNAMIC_VALUES
2016-03-11 17:54:01.282 [INFO ] [me.event.ThingStatusInfoChangedEvent] - 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' changed from ONLINE: DYNAMIC_VALUES to ONLINE

It’s hard to say what’s up. It looks like you created two things for the same node though?

Anyway, the log doesn’t really help as it has no debug information…

I didn’t intentionally create a second thing, it’s still sitting in in box wanting me to. How do I get the debug info?

I’m inclined to delete the original Thing for node 5, but am concerned that the one now in the inbox is showing as “unknown” rather than as aeon_dsc06 (which is correct)

Strange - the log shows that they are both created.

2016-03-11 17:52:44.360 [INFO ] [smarthome.event.ThingAddedEvent     ] - Thing 'zwave:aeon_dsc06_00_000:e2e1b51e:node5' has been added.
2016-03-11 17:52:44.449 [INFO ] [smarthome.event.ThingAddedEvent     ] - Thing 'zwave:serial_zstick:e2e1b51e' has been added.

Maybe clear the inbox and see if that helps, and then do a rescan… In any case, a debug log would help…

oh, I see. the second line of those is the controller (node 1), not the switch. Should it not be showing up that way?

is there a way to limit the debug logging to just this binding? it looks like my Denon binding is spamming it when I enable it generally.

Strange is that your log-entry isn’t truncatet like mine:
[INFO ] [smarthome.event.ThingAddedEvent ]
[INFO ] [marthome.event.ItemStateChangedEvent]
[INFO ] 1234567890123456789012345678901234

Maybe i see ghosts after spending time on seeing to much log files this week :wink:

So I deleted both versions of the Node 5 Thing, and then uninstalled & reinstalled the binding for good measure, and now on startup there are two Things in the inbox, both Node 5, one with the switch correctly identified and one “unknown”.


Chose the good one and setup seemed seems to be working, re-linked the channels to my items and the switch item works, power doesn’t (as before). Timer based rule works, I’ll see if my cron-based rules work overnight.
not sure if this log is helpful or not: zwave log

How does one configure a meter_reset with the 2.0 ZWave binding?

In 1.8 I configured a Switch Item like this: { zwave="6:command=METER,meter_reset=true", autoupdate="false" }. Through a sitemap I would then trigger the switch and the meters/counters in the ZWave device would be reset to 0 (for instance when using Aeon Labs Smart Switch 6 or Aeon HEM G2). I cant find any hint anywhere what the equivalent for OpenHAB2 with ZWave 2.0 binding should be.

mine is also truncated since oh2
thought/think its intented

that started to happen to me after using oh2/zwave2 for a while and never stopped again
when I delete them these “ghost” duplicates are discovered again when I restart oh2

that started to happen to me after using oh2/zwave2 for a while and never stopped again
when I delete them these “ghost” duplicates are discovered again when I restart oh2

I have them too. I delete them, but as you say they come back. But they don’t reappear on every restart for me, I think(?).

Chris, one thing that I think would be good, would be some kind of report of node status. I have several nodes that are in various “not fully online” phases. Now I try to manually maintain this report, with the help of the debug log, but it is very time consuming, having many nodes.
E.g. nodes that are not fully online, and the reason for this.

(Maybe there are already such list/report in Karaf, that I am unaware of?)

We will need to add a new channel for this - can you add an issue on the issue tracker please.

From the Karaf console, type log:set debug org.openhab.binding.zwave.[quote=“mishakim, post:552, topic:7522”]
oh, I see. the second line of those is the controller (node 1), not the switch.
[/quote]

Sorry - you’re right (it was too late last night :wink:).

Yes, but did you actually clear the inbox? I’m not sure how this is done in PaperUI, but there’s a ‘Clear’ button in HABmin to clear the list. Uninstalling the binding will make no difference to the inbox or things.