[SOLVED] Openhab 2.4 zwave thing items not updated

Noddy · August 20, 2018, 4:29pm

Hi,

I’ve some problems to get a DEVOLO MT2653 Keyforb running.

The zwave node 61 is the MT2653. Pressing a button sends a command class
SCENE_ACTIVATION with the value of the pressed button, but the channel “zwave:device:c1f45758:node61:scene_number” isn’t updated. (see log below)

2018-08-20 17:45:20.821 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 3D 04 2B 01 0B FF 16
2018-08-20 17:45:20.828 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-08-20 17:45:20.836 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 3D 04 2B 01 0B FF 16
2018-08-20 17:45:20.843 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 3D 04 2B 01 0B FF 16
2018-08-20 17:45:20.850 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 3D 04 2B 01 0B FF
2018-08-20 17:45:20.855 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 61: Application Command Request (ALIVE:DONE)
2018-08-20 17:45:20.860 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 61: Starting initialisation from DONE
2018-08-20 17:45:20.867 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@161b0b9 already registered
2018-08-20 17:45:20.873 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 61: Incoming command class SCENE_ACTIVATION
2018-08-20 17:45:20.878 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - Received Scene Activation for Node ID = 61
2018-08-20 17:45:20.883 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - Scene Activation Set
2018-08-20 17:45:20.889 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - Scene activation node from node 61: Scene 11, Time 255
2018-08-20 17:45:20.894 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent
2018-08-20 17:45:20.900 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 61: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2018-08-20 17:45:20.905 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 61: Got a value event from Z-Wave network, endpoint = 0, command class = SCENE_ACTIVATION, value = 11
2018-08-20 17:45:20.911 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 29: Transaction not completed: node address inconsistent. lastSent=29, incoming=255

I configured my items in an item file:

```csv
Number	RC_DEVOLO_01_Scene_Number	"RC_01_DEVOLO_MT2653: Scene Number"     <switch>                            { channel="zwave:device:c1f45758:node61:scene_number" }
Number	RC_DEVOLO_01_Battery_Level   "RC_01_DEVOLO_MT2653: Battery Level"    <battery>   (G_BATTERY_DEVICES)     { channel="zwave:device:c1f45758:node61:battery-level" }

The channel “zwave:device:c1f45758:node61:battery-level” and my configured item RC_DEVOLO_01_Battery_Level are updated, if I’m press the button 2 in management mode of MT2653 (Sending Wakeup notification and node information frame).

My system is: openhabian (raspberry pi) and openHAB 2.4.0~20180818003217-1 (Build #1332)

Does anybody has an idea what’s wrong with my configuration?

Thanks
Norbert

sihui · August 20, 2018, 4:35pm

Looks like the association group is not set:

Make sure group 1 Lifeline is set to your controller.

Noddy · August 20, 2018, 5:09pm

Hi sihui,

Thanks for the hint.
There was another node as well in the lifeline. I changed it in HABmin (see screen shot).

I’ve currently set the MT2653 about 30 times into management mode and pressed button 2.
I think I need now a new battery to continue, because yesterday I’ve tried that also for 5 hours

I’ll let you know the results.

These kind of devices are not my favorites

BTW: Thre were also 2 other config changes (group 4 and 5) pending. These are now send to the remote.

Bye
Norbert

Noddy · August 20, 2018, 7:22pm

I’ve resetted the DEVOLO MT2653 to factory default and did an inclusion once again.
The new node ID is 62.
The interesting thing is that now the configuration is send and received directly after pressing button 2 in management mode. This will increase the lifetime of the battery very much.

The items were changed accordingly:

Number	RC_DEVOLO_01_Scene_Number	"RC_01_DEVOLO_MT2653: Scene Number"     <switch>                            { channel="zwave:device:c1f45758:node62:scene_number" }
Number	RC_DEVOLO_01_Battery_Level   "RC_01_DEVOLO_MT2653: Battery Level"    <battery>   (G_BATTERY_DEVICES)     { channel="zwave:device:c1f45758:node62:battery-level" }

The channel received updates (value 255) if any button were pressed. The action for each assoziation group is set to default (Switch On/Off and Dim).

I changed that the action to “Send senses” and the same problem occurs again.
Then I tried “Send Preconfigured Scenses” without luck.

I scrolled the whole list of available option done to the end and found “Central scene to gateway”

grafik

I changed all actions to that value:

That’s working!!
(Hint: I used filter “node 62|node62” in frontail. That’s the reason why my items aren’t shown)

One thing to note is: The scene value changed from integer (11,21,31,41,…) to decimal (1.0, 2.0, 5.0, 6.0)!

2018-08-20 21:07:44.903 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)

2018-08-20 21:07:44.905 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE

2018-08-20 21:07:44.910 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE

2018-08-20 21:07:44.912 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1

2018-08-20 21:07:44.915 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 1 at key 0 [Single Press]

2018-08-20 21:07:44.920 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-20 21:07:44.922 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 1.0

2018-08-20 21:07:44.925 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 1.0 [DecimalType]

2018-08-20 21:07:45.873 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)

2018-08-20 21:07:45.875 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE

2018-08-20 21:07:45.880 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE

2018-08-20 21:07:45.882 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1

2018-08-20 21:07:45.884 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 5 at key 0 [Single Press]

2018-08-20 21:07:45.889 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-20 21:07:45.891 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 5.0

2018-08-20 21:07:45.894 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 5.0 [DecimalType]

2018-08-20 21:07:46.964 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)

2018-08-20 21:07:46.967 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE

2018-08-20 21:07:46.975 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE

2018-08-20 21:07:46.978 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1

2018-08-20 21:07:46.982 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 6 at key 0 [Single Press]

2018-08-20 21:07:46.990 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-20 21:07:46.994 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 6.0

2018-08-20 21:07:46.998 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 6.0 [DecimalType]

2018-08-20 21:07:47.527 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)

2018-08-20 21:07:47.530 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE

2018-08-20 21:07:47.536 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE

2018-08-20 21:07:47.539 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1

2018-08-20 21:07:47.542 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 2 at key 0 [Single Press]

2018-08-20 21:07:47.548 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-20 21:07:47.551 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 2.0

2018-08-20 21:07:47.554 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 2.0 [DecimalType]

Here’s a small log including everything (From message button pressed up to a rule log)

2018-08-20 21:10:44.850 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 3E 05 5B 03 02 00 05 94 

2018-08-20 21:10:44.854 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0

2018-08-20 21:10:44.857 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 04 00 3E 05 5B 03 02 00 05 94 

2018-08-20 21:10:44.861 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0B 00 04 00 3E 05 5B 03 02 00 05 94 

2018-08-20 21:10:44.864 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 3E 05 5B 03 02 00 05 

2018-08-20 21:10:44.868 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Application Command Request (ALIVE:DONE)

2018-08-20 21:10:44.872 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 62: Starting initialisation from DONE

2018-08-20 21:10:44.876 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@15383f7 already registered

2018-08-20 21:10:44.880 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 62: Incoming command class CENTRAL_SCENE

2018-08-20 21:10:44.884 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received CENTRAL_SCENE command V1

2018-08-20 21:10:44.887 [DEBUG] [dclass.ZWaveCentralSceneCommandClass] - NODE 62: Received scene 5 at key 0 [Single Press]

2018-08-20 21:10:44.891 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveCommandClassValueEvent

2018-08-20 21:10:44.895 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got an event from Z-Wave network: ZWaveCommandClassValueEvent

2018-08-20 21:10:44.899 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Got a value event from Z-Wave network, endpoint = 0, command class = CENTRAL_SCENE, value = 5.0

2018-08-20 21:10:44.904 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 62: Updating channel state zwave:device:c1f45758:node62:scene_number to 5.0 [DecimalType]

2018-08-20 21:10:44.911 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 39: Transaction not completed: node address inconsistent.  lastSent=39, incoming=255

==> /var/log/openhab2/events.log <==

2018-08-20 21:10:44.916 [thome.event.ItemStateEvent] - RC_DEVOLO_01_Scene_Number updated to 5.0

2018-08-20 21:10:44.920 [vent.ItemStateChangedEvent] - RC_DEVOLO_01_Scene_Number changed from 2.0 to 5.0

==> /var/log/openhab2/openhab.log <==

2018-08-20 21:10:44.921 [INFO ] [ipse.smarthome.model.script.r_RC_001] - rule r_RC_DEVOLO_01_Scene_Number_chgd (1)

SOLUTION: Take care that the action is set to "Central scene to gateway"

@sihui: Many thanks for your very quick response and directing me to the place to look at!

Regards
Norbert