[SOLVED] Home Energy Meter HEM G3 AEON Labs GEN5 ZW095 do not report automatically

I could be wrong, but your logs look like just the outgoing messages. There should also be an incoming configuration report from the device. But if you don’t have the yellow/orange “pending” message, then these should have come in.

Could you post the rest of your configuration parameters? There are 2-11, 13, 101-103, 111-113, 252, and 255. I’m particularly interested in 3 and any thresholds that you’ve set.

Could you delete the association, save, add the association, and save, all while logging? If the association is not getting set, then you will not get any reports.

Also, if you only have the one clamp, I would clear out any of the reports for the others.

Have you tried setting config param 255 to reset all settings? I don’t recall if the association needs to be reset after doing this.

Hello @5iver,
this is the entire log when I change the parameters of device:
https://nopaste.xyz/?b384dc1dd3ce70f2#F0Uq3wCbb0jZ4Qfji1JTXKlIqz0P5DIid3vMAHof+IM=
This is the screenshoot of the rest for the configuration parameters:




Tomorrow I try to delete and reassociate the device.
Now I have clean clamp 2 and clamp 3 and I set the parameters only for clamp 1.
I hope good news will follow.
PS. I try to set parameters 3 at 1 but the works mode doesn’t change.

Good… you are receiving these…

Received COMMAND_CLASS_CONFIGURATION V1 CONFIGURATIONCMD_REPORT

… so the device is communicating and updating the configs. The device must recognize that you only have the one clamp, so config_102_4 and config_103_4 are coming back as zeros when they are being set.

Your other configuration parameters look good to me, so I suspect the association is not set. Just delete the current association to the controller, save the Thing, then add the controller back in and save. No need to delete the Thing or reinclude the device for the associations, but resetting and reincluding the device would be the next thing to try after resetting the association.

Hi @5iver,
I have delete the current association to the controller and add controller back, but nothing has changed.
I have press, from habmin, reinitialize the device…
In the openhab log I have three of this message (together at lot of messages):

NODE 3: No data from device, but it was ACK’d. Possibly not supported? (Try 3)

it is perhaps a version not yet supported?
This is a screen of attributes

Do you mean you set parameter 255?

No… that is in the database. I think it’s time to exclude, hard reset, and reinclude the device.

Yes, I have set paramater 255 and exclude, reset and reinclude the device.

OK, for hard reset do you mean this:


Thanks for support

No, I meant to reset the monitor. Check the manual, but for mine, you power it off, hold the button, plug it in, and keep holding the button for 10s or until the LED starts blinking.

You are very welcome. Hopefully, we can get your device reporting!

I have already execute this procedure, but without turn off the power. I try with turn off the power but on manual there isn’t reference to turn off.

News will follow with complete log of new inclusion

Hello @5iver,
I have execute the procedure with turn off the power…
I have clean the UZB, clean zwave section of openhab and reinclude the device…
The device doesn’t send voluntarily the value.
This is entire log:
https://nopaste.xyz/?92bd99b4593c9307#uCtjOqfbhilG8W2Z2gXHkm5moJ/yjpLUJ9fBNEeaOpI=
You can view the new parameters in the next screenshot:

This log is password protected. Your settings all look good and your log showed that they were being set. I don’t recall seeing a log with the association being cleared and reset though.

Hi @5iver, i have clean openhab2 with a new installation and I have clean the UZB with Z-Wave PC Controller 5.
For now I settled for the refresh via rules evey 5 seconds.
Thanks

I’ll add my own experience here, nothing new but maybe worthwhile to summarize for someone who’s thinking about getting this device or just wants a quick resolution:

  • It’s not possible to set correct associations with the current Z-Wave stable or snapshot versions -> automatic reporting does not work out of the box.
  • Polling does work, so it is possible to get new values every X seconds/minutes using a rule - you won’t get all the benefits of reporting though.
  • The associations can be set through a software called Z-Wave PC Controller. I’m confused about where to download this officially, but unofficial source is mentioned here.

It was a bit scary doing this without really knowing what I was doing, but I got it working by following the instructions from Austris_V. Newer version of the program looks different, but the same options are there.

Other than that the HEM has been working great (for 30 hours at least…) and it has good options for finetuning the reports. Hopefully whatever needs to happen to get the associations working without 3rd party software happens, but unfortunately my knowledge on this area is not enough to further that cause. :frowning:

1 Like

I’ve not read through this thread, but can you summarise what the issue is please? What is stopping the associations being set? Can you provide a debug log showing the problem?

Since it looks like this device is a ZWave+ device, the binding should set the lifeline association group automatically during the initialisation of the device - if this isn’t working it would be great to see the debug log so it can be resolved.

Thanks.

Setting association group 3 to “Controller”:

19:15:05.255 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update received
19:15:05.274 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set group_3 to [controller] (ArrayList)
19:15:05.283 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Association 3 consolidated to [controller]
19:15:05.297 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Unknown association group 3

Habmin gives no errors.

I have the whole openhab.log from starting the binding and creating the .xml file for the node if you need that too. Just let me know how I can deliver it (no idea if it contains sensitive information or not and it’s too long to check).

I couldn’t be 100% sure of the reason here without seeing the log, but basically it means that this association group wasn’t discovered in the device during the initialisation. This error generally means that the initialisation is not 100% complete and it has not found this association group, but it might also be that the device doesn’t actually support this group.

That said, this is a ZWave+ device - normally only the Lifeline group (association group 1) is required to be set to the controller.

Looking at the engineering manual, it says there is only 1 association group anyway, which would explain this error. It then means that the database is incorrect.

So, I don’t think that this error you see in the log is a problem as this association group doesn’t actually exist it seems. The question still is if the lifeline association group is working. V2.5 of the binding should set that automatically and it should not be possible to remove it so it would be good to see the log with the full initialisation if possible.

Sorry, I must have confused the reporting groups with association groups. Here’s another log with association group 1.

Seeing “node_1_1” in there reminded me: when looking around with the Z-Wave PC Controller, I saw that before adding Node 1, there was already Node 1.1 associated. Not sure if that’s of any significance.

Removing "Controller" from association group 1:

21:04:56.577 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update received
21:04:56.599 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set group_1 to [] (EmptyList)
21:04:56.609 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Association 1 consolidated to []
21:04:56.618 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Current members before update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1, node_1_1]]
21:04:56.626 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Members after config update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[]]
21:04:56.640 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller is master - forcing associations
21:04:56.669 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Group is controller - forcing association
21:04:56.678 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Members after controller update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]]
21:04:56.690 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Removing node_1 from association group 1
21:04:56.699 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Creating new message for command MULTI_ASSOCIATIONCMD_REMOVE
21:04:56.712 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:04:56.720 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION is NOT required to be secured
21:04:56.729 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
21:04:56.737 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Added 700 to queue - size 4
21:04:56.745 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:04:56.758 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 08 06 8E 04 01 00 01 00 25 47 07 
21:04:56.769 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0D 00 13 08 06 8E 04 01 00 01 00 25 47 07 
21:04:56.779 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
21:04:56.782 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
21:04:56.793 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 700: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 71
21:04:56.802 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:04:56.806 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Creating new message for command MULTI_ASSOCIATIONCMD_GET group 1
21:04:56.815 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
21:04:56.811 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:04:56.825 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:04:56.837 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 700: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 71
21:04:56.843 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION is NOT required to be secured
21:04:56.854 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:56.860 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
21:04:56.863 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
21:04:56.873 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:56.872 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 47 00 00 02 AE 
21:04:56.877 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Added 701 to queue - size 4
21:04:56.887 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 700: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 71
21:04:56.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
21:04:56.904 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02 
21:04:56.916 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:04:56.926 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 700: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 71
21:04:56.934 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:56.945 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.
21:04:56.954 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 700: Advanced to WAIT_REQUEST
21:04:56.964 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 700: Transaction not completed
21:04:56.974 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02 
21:04:56.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 700: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 71
21:04:56.992 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:04:57.000 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 700: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 71
21:04:57.008 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 700: (Callback 71)
21:04:57.017 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
21:04:57.028 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 700: callback 71
21:04:57.036 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=71, payload=47 00 00 02 
21:04:57.047 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 71, Status = Transmission complete and ACK received(0)
21:04:57.065 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:04:57.072 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 700: Transaction COMPLETED
21:04:57.083 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 290ms
21:04:57.091 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 700: Transaction completed
21:04:57.100 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:700 DONE
21:04:57.119 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
21:04:57.126 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:04:57.135 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:04:57.146 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 08 03 8E 02 01 25 48 0D 
21:04:57.159 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 8E 02 01 25 48 0D 
21:04:57.165 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
21:04:57.169 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
21:04:57.175 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 701: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 72
21:04:57.181 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:04:57.199 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:04:57.200 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
21:04:57.206 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 701: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 72
21:04:57.220 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:57.220 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
21:04:57.237 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 48 00 00 02 A1 
21:04:57.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:57.252 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 02 
21:04:57.254 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 701: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 72
21:04:57.268 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:04:57.269 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 08 09 8E 03 01 05 00 01 00 01 01 7D 
21:04:57.277 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 701: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 72
21:04:57.289 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 8E 03 01 05 00 01 00 01 01 
21:04:57.291 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:04:57.303 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.
21:04:57.312 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 701: Advanced to WAIT_REQUEST
21:04:57.322 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 701: Transaction not completed
21:04:57.330 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 02 
21:04:57.338 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 701: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 72
21:04:57.346 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:04:57.354 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 701: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 72
21:04:57.360 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 701: (Callback 72)
21:04:57.369 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
21:04:57.376 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 701: callback 72
21:04:57.384 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=72, payload=48 00 00 02 
21:04:57.394 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 72, Status = Transmission complete and ACK received(0)
21:04:57.403 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:04:57.412 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 701: Advanced to WAIT_DATA
21:04:57.420 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 701: Transaction not completed
21:04:57.428 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 8E 03 01 05 00 01 00 01 01 
21:04:57.439 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
21:04:57.447 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
21:04:57.455 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:04:57.466 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0
21:04:57.475 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:04:57.485 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION V3 MULTI_ASSOCIATIONCMD_REPORT
21:04:57.494 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: association group 1 has max associations 5
21:04:57.501 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Association group 1 includes the following nodes:
21:04:57.510 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Associated with Node 1 in group 1
21:04:57.516 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Associated with Node 1 endpoint 1 in group
21:04:57.523 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAssociationEvent
21:04:57.536 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0
21:04:57.544 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
21:04:57.552 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6c4b06.
21:04:57.564 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@6c4b06.
21:04:57.570 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:701 DONE
21:04:57.579 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
21:04:57.586 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
21:04:57.594 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:04:57.600 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:04:57.611 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:05:22.755 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 00 D5 
21:05:22.769 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
21:05:22.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
21:05:22.796 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
21:05:22.805 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
21:05:22.813 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
21:05:22.823 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
21:05:22.836 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BASIC
21:05:22.845 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BASIC V1 BASIC_SET
21:05:22.853 [DEBUG] [l.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0
21:05:22.860 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
21:05:22.865 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
21:05:22.874 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
21:05:22.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@b5a6e8.
21:05:22.886 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:05:22.895 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:05:22.901 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:05:22.910 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:05:23.077 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 00 00 68 
21:05:23.089 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
21:05:23.101 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
21:05:23.107 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
21:05:23.115 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
21:05:23.122 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
21:05:23.135 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
21:05:23.143 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM
21:05:23.149 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
21:05:23.158 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
21:05:23.165 [DEBUG] [l.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0)
21:05:23.174 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent
21:05:23.181 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
21:05:23.188 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
21:05:23.204 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
21:05:23.212 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_motion to OFF [OnOffType]
21:05:23.223 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
21:05:23.232 [DEBUG] [nternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
21:05:23.240 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_tamper to OFF [OnOffType]
21:05:23.251 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
21:05:23.259 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@136582a.
21:05:23.271 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:05:23.286 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:05:23.298 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:05:23.306 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Adding "Controller" to association group 1:

21:08:26.655 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update received
21:08:26.671 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Configuration update set group_1 to [controller] (ArrayList)
21:08:26.680 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Association 1 consolidated to [controller]
21:08:26.688 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Current members before update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1, node_1_1]]
21:08:26.694 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Members after config update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]]
21:08:26.702 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Controller is master - forcing associations
21:08:26.725 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Group is controller - forcing association
21:08:26.731 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Members after controller update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]]
21:08:26.741 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Removing node_1 from association group 1
21:08:26.751 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Creating new message for command MULTI_ASSOCIATIONCMD_REMOVE
21:08:26.759 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:08:26.768 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION is NOT required to be secured
21:08:26.775 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
21:08:26.783 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Added 702 to queue - size 4
21:08:26.791 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:08:26.802 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 08 06 8E 04 01 00 01 00 25 49 09 
21:08:26.812 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0D 00 13 08 06 8E 04 01 00 01 00 25 49 09 
21:08:26.819 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
21:08:26.822 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
21:08:26.834 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:08:26.833 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 702: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 73
21:08:26.843 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:08:26.847 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Creating new message for command MULTI_ASSOCIATIONCMD_GET group 1
21:08:26.844 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
21:08:26.857 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:08:26.866 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 702: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 73
21:08:26.873 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Command Class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION is NOT required to be secured
21:08:26.868 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:26.883 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Adding to device queue
21:08:26.877 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
21:08:26.893 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Added 703 to queue - size 4
21:08:26.900 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:26.906 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
21:08:26.899 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 49 00 00 02 A0 
21:08:26.918 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 702: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 73
21:08:26.927 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:08:26.928 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 02 
21:08:26.940 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 702: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 73
21:08:26.940 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 31 05 05 01 3A FA 
21:08:26.947 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:26.960 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.
21:08:26.962 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 05 01 3A 
21:08:26.968 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 702: Advanced to WAIT_REQUEST
21:08:26.978 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 702: Transaction not completed
21:08:26.989 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 02 
21:08:26.997 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 702: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 73
21:08:27.005 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:08:27.016 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 702: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 73
21:08:27.025 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 702: (Callback 73)
21:08:27.033 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
21:08:27.042 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 702: callback 73
21:08:27.064 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=73, payload=49 00 00 02 
21:08:27.074 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 73, Status = Transmission complete and ACK received(0)
21:08:27.082 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:08:27.091 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 702: Transaction COMPLETED
21:08:27.099 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Response processed after 265ms
21:08:27.108 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 702: Transaction completed
21:08:27.115 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:702 DONE
21:08:27.124 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
21:08:27.132 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 31 05 05 01 3A 
21:08:27.139 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
21:08:27.145 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
21:08:27.154 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
21:08:27.160 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_SENSOR_MULTILEVEL, endpoint 0
21:08:27.166 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_SENSOR_MULTILEVEL
21:08:27.172 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_SENSOR_MULTILEVEL V5 SENSOR_MULTILEVEL_REPORT
21:08:27.179 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Type = RelativeHumidity(5), Scale = 0
21:08:27.186 [DEBUG] [ass.ZWaveMultiLevelSensorCommandClass] - NODE 5: Sensor Value = 58
21:08:27.193 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveMultiLevelSensorValueEvent
21:08:27.200 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_MULTILEVEL, value=58
21:08:27.207 [DEBUG] [verter.ZWaveMultiLevelSensorConverter] - NODE 5: Sensor conversion not performed for RELATIVE_HUMIDITY.
21:08:27.215 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:sensor_relhumidity to 58 [DecimalType]
21:08:27.226 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
21:08:27.239 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@101262e.
21:08:27.255 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:08:27.269 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:08:27.277 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:08:27.285 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
21:08:27.297 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 08 03 8E 02 01 25 4A 0F 
21:08:27.306 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 8: Sending REQUEST Message = 01 0A 00 13 08 03 8E 02 01 25 4A 0F 
21:08:27.315 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT
21:08:27.318 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
21:08:27.327 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 703: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 74
21:08:27.336 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:08:27.350 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
21:08:27.351 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
21:08:27.357 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:27.365 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 703: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 74
21:08:27.371 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 4A 00 00 02 A3 
21:08:27.376 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK
21:08:27.383 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:27.387 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02 
21:08:27.389 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 703: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 74
21:08:27.397 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:08:27.402 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 703: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 74
21:08:27.407 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
21:08:27.410 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0F 00 04 00 08 09 8E 03 01 05 00 01 00 01 01 7D 
21:08:27.416 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: sentData successfully placed on stack.
21:08:27.427 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 703: Advanced to WAIT_REQUEST
21:08:27.437 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 703: Transaction not completed
21:08:27.440 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 8E 03 01 05 00 01 00 01 01 
21:08:27.447 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02 
21:08:27.459 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 703: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 74
21:08:27.468 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
21:08:27.475 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 703: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 74
21:08:27.483 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 703: (Callback 74)
21:08:27.489 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match!
21:08:27.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 703: callback 74
21:08:27.501 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=74, payload=4A 00 00 02 
21:08:27.512 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 8: SendData Request. CallBack ID = 74, Status = Transmission complete and ACK received(0)
21:08:27.521 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:08:27.527 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 703: Advanced to WAIT_DATA
21:08:27.532 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: TID 703: Transaction not completed
21:08:27.540 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 09 8E 03 01 05 00 01 00 01 01 
21:08:27.548 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null
21:08:27.553 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
21:08:27.559 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
21:08:27.565 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0
21:08:27.571 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
21:08:27.580 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION V3 MULTI_ASSOCIATIONCMD_REPORT
21:08:27.586 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: association group 1 has max associations 5
21:08:27.592 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Association group 1 includes the following nodes:
21:08:27.597 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Associated with Node 1 in group 1
21:08:27.603 [DEBUG] [ass.ZWaveMultiAssociationCommandClass] - NODE 8: Associated with Node 1 endpoint 1 in group
21:08:27.609 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveAssociationEvent
21:08:27.614 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0
21:08:27.621 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
21:08:27.626 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d28bb.
21:08:27.632 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d28bb.
21:08:27.637 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 8: notifyTransactionResponse TID:703 DONE
21:08:27.644 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
21:08:27.650 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
21:08:27.655 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
21:08:27.660 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
21:08:27.665 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

I can send the whole initialisation log if you tell me how to send it to you.

It’s not possible to remove the controller from group 1 - as I mentioned earlier, in the 2.5 binding it is forced to be set if the binding detects that the group is the lifeline. It reads this directly from the device itself.

We can see that even though you set the group as empty, it is still set to node_1_1

This is exactly the same as when you set the group to the controller -:

Generally, this looks fine - it confirms that the binding is setting the associations. The only thing to note is that the binding has used the multi-channel version of the command class. This should be fine, but may impact on the messages the device sends and could be a problem, but I can’t tell from this log.

It would be interesting to see if the device is sending anything following this - if it is I’d be interested to see what it is.

You mean keep the log running for a while after these operations? For how long?

Yes.

I’m not sure what the device does - maybe 1 hour should be ok (??).

This is right after the last log. The forum is only letting me post 48000 characters.

2019-08-24 21:08:27.665 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:10:43.320 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 32 04 5E 00 9B 00 30 8E 92 53 
2019-08-24 21:10:43.331 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 32 04 5E 00 9B 00 30 8E 92 
2019-08-24 21:10:43.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 32 04 5E 00 9B 00 30 8E 92 
2019-08-24 21:10:43.339 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:10:43.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:10:43.345 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:10:43.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:10:43.351 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:10:43.354 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:10:43.359 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3277.918
2019-08-24 21:10:43.362 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:10:43.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3277.918
2019-08-24 21:10:43.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3277.918 [DecimalType]
2019-08-24 21:10:43.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:10:43.379 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f8f4c3.
2019-08-24 21:10:43.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:10:43.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:10:43.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:10:43.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:10:48.320 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 34 D8 DF 00 05 00 32 04 5E D2 
2019-08-24 21:10:48.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 34 D8 DF 00 05 00 32 04 5E 
2019-08-24 21:10:48.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 34 D8 DF 00 05 00 32 04 5E 
2019-08-24 21:10:48.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:10:48.347 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:10:48.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:10:48.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:10:48.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:10:48.358 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:10:48.361 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3463.391
2019-08-24 21:10:48.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:10:48.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3463.391
2019-08-24 21:10:48.370 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3463.391 [DecimalType]
2019-08-24 21:10:48.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:10:48.376 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@3fbbe.
2019-08-24 21:10:48.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:10:48.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:10:48.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:10:48.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:11:03.321 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 35 F2 C3 00 0F 00 34 D8 DF B4 
2019-08-24 21:11:03.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 35 F2 C3 00 0F 00 34 D8 DF 
2019-08-24 21:11:03.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 35 F2 C3 00 0F 00 34 D8 DF 
2019-08-24 21:11:03.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:11:03.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:11:03.351 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:11:03.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:11:03.358 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:11:03.361 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:11:03.366 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3535.555
2019-08-24 21:11:03.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:11:03.373 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3535.555
2019-08-24 21:11:03.377 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3535.555 [DecimalType]
2019-08-24 21:11:03.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:11:03.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a395c6.
2019-08-24 21:11:03.390 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:03.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:03.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:11:03.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:11:58.546 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 00 D5 
2019-08-24 21:11:58.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
2019-08-24 21:11:58.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
2019-08-24 21:11:58.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:11:58.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:11:58.567 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:11:58.571 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2019-08-24 21:11:58.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-08-24 21:11:58.577 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2019-08-24 21:11:58.585 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0
2019-08-24 21:11:58.589 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-08-24 21:11:58.592 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2019-08-24 21:11:58.596 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:11:58.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@18a9c08.
2019-08-24 21:11:58.601 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:58.604 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:58.606 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:11:58.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:11:58.868 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 00 00 68 
2019-08-24 21:11:58.875 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
2019-08-24 21:11:58.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
2019-08-24 21:11:58.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:11:58.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:11:58.885 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:11:58.887 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-08-24 21:11:58.890 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM
2019-08-24 21:11:58.892 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2019-08-24 21:11:58.895 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
2019-08-24 21:11:58.897 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0)
2019-08-24 21:11:58.900 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-08-24 21:11:58.902 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2019-08-24 21:11:58.906 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:11:58.908 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-24 21:11:58.911 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_motion to OFF [OnOffType]
2019-08-24 21:11:58.916 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:11:58.920 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-24 21:11:58.923 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_tamper to OFF [OnOffType]
2019-08-24 21:11:58.930 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:11:58.932 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@b544c.
2019-08-24 21:11:58.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:58.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:11:58.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:11:58.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:12:58.316 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 49 D6 8A 00 73 00 35 F2 C3 EE 
2019-08-24 21:12:58.327 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 49 D6 8A 00 73 00 35 F2 C3 
2019-08-24 21:12:58.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 49 D6 8A 00 73 00 35 F2 C3 
2019-08-24 21:12:58.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:12:58.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:12:58.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:12:58.344 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:12:58.347 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:12:58.350 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:12:58.354 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=4839.05
2019-08-24 21:12:58.357 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:12:58.360 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=4839.05
2019-08-24 21:12:58.364 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 4839.05 [DecimalType]
2019-08-24 21:12:58.369 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:12:58.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@caea2e.
2019-08-24 21:12:58.379 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:12:58.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:12:58.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:12:58.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:13:03.316 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 38 61 0A 00 05 00 49 D6 8A CF 
2019-08-24 21:13:03.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 38 61 0A 00 05 00 49 D6 8A 
2019-08-24 21:13:03.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 38 61 0A 00 05 00 49 D6 8A 
2019-08-24 21:13:03.340 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:13:03.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:13:03.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:13:03.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:13:03.355 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:13:03.359 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:13:03.365 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3694.858
2019-08-24 21:13:03.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:13:03.373 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3694.858
2019-08-24 21:13:03.378 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3694.858 [DecimalType]
2019-08-24 21:13:03.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:13:03.386 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@fc50d1.
2019-08-24 21:13:03.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:13:03.392 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:13:03.395 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:13:03.399 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:14:36.080 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 FF 2A 
2019-08-24 21:14:36.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 FF 
2019-08-24 21:14:36.093 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 FF 
2019-08-24 21:14:36.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:14:36.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:14:36.106 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:14:36.110 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2019-08-24 21:14:36.114 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-08-24 21:14:36.118 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2019-08-24 21:14:36.123 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 255
2019-08-24 21:14:36.127 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-08-24 21:14:36.132 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=255
2019-08-24 21:14:36.137 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:14:36.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@12de61a.
2019-08-24 21:14:36.145 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:36.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:36.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:14:36.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:14:37.444 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 08 00 00 60 
2019-08-24 21:14:37.454 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 08 00 00 
2019-08-24 21:14:37.460 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 08 00 00 
2019-08-24 21:14:37.464 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:14:37.467 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:14:37.470 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:14:37.474 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-08-24 21:14:37.477 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM
2019-08-24 21:14:37.480 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2019-08-24 21:14:37.483 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=8, status=255, plen=0
2019-08-24 21:14:37.486 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0)
2019-08-24 21:14:37.490 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-08-24 21:14:37.493 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2019-08-24 21:14:37.498 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:14:37.501 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType
2019-08-24 21:14:37.505 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_motion to ON [OnOffType]
2019-08-24 21:14:37.510 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:14:37.515 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 8, type OnOffType
2019-08-24 21:14:37.520 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:14:37.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@16e6c07.
2019-08-24 21:14:37.526 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:37.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:37.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:14:37.536 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:14:53.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 39 2A 2E 00 6E 00 38 61 0A 8C 
2019-08-24 21:14:53.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 39 2A 2E 00 6E 00 38 61 0A 
2019-08-24 21:14:53.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 39 2A 2E 00 6E 00 38 61 0A 
2019-08-24 21:14:53.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:14:53.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:14:53.344 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:14:53.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:14:53.353 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:14:53.358 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:14:53.364 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3746.35
2019-08-24 21:14:53.369 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:14:53.372 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3746.35
2019-08-24 21:14:53.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3746.35 [DecimalType]
2019-08-24 21:14:53.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:14:53.398 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@de679e.
2019-08-24 21:14:53.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:53.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:14:53.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:14:53.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:15:23.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 36 69 3B 00 1E 00 39 2A 2E CB 
2019-08-24 21:15:23.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 36 69 3B 00 1E 00 39 2A 2E 
2019-08-24 21:15:23.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 36 69 3B 00 1E 00 39 2A 2E 
2019-08-24 21:15:23.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:15:23.336 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:15:23.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:15:23.346 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:15:23.350 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:15:23.355 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:15:23.361 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3565.883
2019-08-24 21:15:23.366 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:15:23.371 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3565.883
2019-08-24 21:15:23.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3565.883 [DecimalType]
2019-08-24 21:15:23.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:15:23.387 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@46cacc.
2019-08-24 21:15:23.391 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:15:23.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:15:23.397 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:15:23.400 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:15:28.311 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 32 4F F6 00 05 00 36 69 3B 66 
2019-08-24 21:15:28.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 32 4F F6 00 05 00 36 69 3B 
2019-08-24 21:15:28.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 32 4F F6 00 05 00 36 69 3B 
2019-08-24 21:15:28.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:15:28.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:15:28.344 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:15:28.349 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:15:28.352 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:15:28.356 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:15:28.360 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=3297.27
2019-08-24 21:15:28.365 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:15:28.368 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=3297.27
2019-08-24 21:15:28.373 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 3297.27 [DecimalType]
2019-08-24 21:15:28.380 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:15:28.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@8ff2e8.
2019-08-24 21:15:28.394 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:15:28.400 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:15:28.403 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:15:28.409 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:17:47.531 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 10 00 04 00 05 0A 71 05 00 00 00 FF 07 00 00 00 68 
2019-08-24 21:17:47.548 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
2019-08-24 21:17:47.558 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 0A 71 05 00 00 00 FF 07 00 00 00 
2019-08-24 21:17:47.564 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:17:47.569 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:17:47.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:17:47.580 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ALARM, endpoint 0
2019-08-24 21:17:47.586 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ALARM
2019-08-24 21:17:47.590 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ALARM V3 NOTIFICATION_REPORT
2019-08-24 21:17:47.596 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: NOTIFICATION report - 0 = 0, event=0, status=255, plen=0
2019-08-24 21:17:47.600 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 5: Alarm Type = BURGLAR (0)
2019-08-24 21:17:47.605 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAlarmValueEvent
2019-08-24 21:17:47.610 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ALARM, value=255
2019-08-24 21:17:47.616 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:17:47.620 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-24 21:17:47.626 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_motion to OFF [OnOffType]
2019-08-24 21:17:47.632 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter processing NOTIFICATION
2019-08-24 21:17:47.636 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 5: Alarm converter NOTIFICATION event is 0, type OnOffType
2019-08-24 21:17:47.639 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Updating channel state zwave:device:cad84a12:node5:alarm_tamper to OFF [OnOffType]
2019-08-24 21:17:47.646 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:17:47.649 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@a3a12a.
2019-08-24 21:17:47.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:17:47.656 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:17:47.659 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:17:47.663 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:17:50.022 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 05 03 20 01 00 D5 
2019-08-24 21:17:50.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
2019-08-24 21:17:50.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 03 20 01 00 
2019-08-24 21:17:50.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:17:50.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE)
2019-08-24 21:17:50.053 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false
2019-08-24 21:17:50.056 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_BASIC, endpoint 0
2019-08-24 21:17:50.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_BASIC
2019-08-24 21:17:50.063 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_BASIC V1 BASIC_SET
2019-08-24 21:17:50.067 [DEBUG] [.commandclass.ZWaveBasicCommandClass] - NODE 5: Basic report, value = 0
2019-08-24 21:17:50.069 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2019-08-24 21:17:50.072 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_BASIC, value=0
2019-08-24 21:17:50.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1.
2019-08-24 21:17:50.078 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c8d9f7.
2019-08-24 21:17:50.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:17:50.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:17:50.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:17:50.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:18:18.308 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 02 93 74 00 AA 00 32 4F F6 48 
2019-08-24 21:18:18.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 02 93 74 00 AA 00 32 4F F6 
2019-08-24 21:18:18.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 02 93 74 00 AA 00 32 4F F6 
2019-08-24 21:18:18.329 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:18:18.333 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:18:18.341 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:18:18.345 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:18:18.348 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:18:18.351 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:18:18.355 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=168.82
2019-08-24 21:18:18.358 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:18:18.361 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=168.82
2019-08-24 21:18:18.365 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 168.82 [DecimalType]
2019-08-24 21:18:18.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:18:18.375 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1767f68.
2019-08-24 21:18:18.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:18:18.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:18:18.389 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:18:18.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2019-08-24 21:18:23.307 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 14 00 04 00 08 0E 32 02 21 74 00 02 EA 0E 00 05 00 02 93 74 8A 
2019-08-24 21:18:23.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 02 EA 0E 00 05 00 02 93 74 
2019-08-24 21:18:23.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=8, callback=0, payload=00 08 0E 32 02 21 74 00 02 EA 0E 00 05 00 02 93 74 
2019-08-24 21:18:23.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2019-08-24 21:18:23.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Application Command Request (ALIVE:DONE)
2019-08-24 21:18:23.340 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: resetResendCount initComplete=true isDead=false
2019-08-24 21:18:23.346 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: Incoming command class COMMAND_CLASS_METER, endpoint 0
2019-08-24 21:18:23.351 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 8: SECURITY not supported
2019-08-24 21:18:23.357 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 8: Received COMMAND_CLASS_METER V3 METER_REPORT
2019-08-24 21:18:23.364 [DEBUG] [.commandclass.ZWaveMeterCommandClass] - NODE 8: Meter: Type=Electric(1), Scale=W(2), Value=190.99
2019-08-24 21:18:23.370 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got an event from Z-Wave network: ZWaveMeterValueEvent
2019-08-24 21:18:23.376 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_METER, value=190.99
2019-08-24 21:18:23.381 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 8: Updating channel state zwave:device:cad84a12:node8:meter_watts to 190.99 [DecimalType]
2019-08-24 21:18:23.388 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Commands processed 1.
2019-08-24 21:18:23.393 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 8: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@5ddc1c.
2019-08-24 21:18:23.396 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:18:23.401 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2019-08-24 21:18:23.404 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2019-08-24 21:18:23.411 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.