Unable to change Association Group for ZWave devices

DaHarry · April 27, 2020, 1:07pm

Hi,

i have a Popp Rain Sensor where i cant received any Data from.

I can include and OpenHab can Find and identificate the Device. However, i cant received any Date. If i update the Association Group, OpenHab dos not store this correctly. In the Debug Log I get:

14:57:10.871 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Configuration update received
14:57:10.895 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Configuration update ignored binding_cmdrepollperiod to 1500 (BigDecimal)
14:57:10.899 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Configuration update ignored action_failed to false (Boolean)
14:57:10.904 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Configuration update ignored wakeup_interval to 3600 (BigDecimal)
14:57:10.908 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Configuration update set group_1 to [controller] (ArrayList)
14:57:10.912 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Association 1 consolidated to [controller]
14:57:10.915 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 53: Unknown association group 1
....

I try multiple include the Device to OH, but Nothing change … Does have anyone an Idea
I Run the last Release Build 2.5.4

DaHarry · April 27, 2020, 3:05pm

If i wake up the Rain Sensor, i get the following DEBUG Logs in loop

2020-04-27 16:55:14.902 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 53: WakeupTimerTask 1 Messages waiting, state APP_VERSION
2020-04-27 16:55:14.904 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 53: No more messages, go back to sleep
2020-04-27 16:55:14.907 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 53: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2020-04-27 16:55:14.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 53: SECURITY not supported
2020-04-27 16:55:14.912 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 53: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2020-04-27 16:55:14.914 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e45212
2020-04-27 16:55:14.917 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: Bump transaction 222 priority from Immediate to Immediate
2020-04-27 16:55:14.919 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: Adding to device queue
2020-04-27 16:55:14.921 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: Added 222 to queue - size 23
2020-04-27 16:55:14.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-27 16:55:14.925 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 35 02 86 11 25 8B EB
2020-04-27 16:55:14.928 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 53: Sending REQUEST Message = 01 09 00 13 35 02 86 11 25 8B EB
2020-04-27 16:55:14.930 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-27 16:55:14.931 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-27 16:55:14.932 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:14.934 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:14.936 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:14.938 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:14.939 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-27 16:55:14.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-27 16:55:14.941 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-04-27 16:55:14.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-27 16:55:14.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:14.947 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:14.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:14.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-27 16:55:14.952 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:14.954 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:14.956 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 53: sentData successfully placed on stack.
2020-04-27 16:55:14.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 221: Advanced to WAIT_REQUEST
2020-04-27 16:55:14.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: TID 221: Transaction not completed
2020-04-27 16:55:14.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-27 16:55:14.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-27 16:55:19.961 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 53: TID 221: Timeout at state WAIT_REQUEST. 2 retries remaining.
2020-04-27 16:55:19.963 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2020-04-27 16:55:19.964 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 221: Transaction ABORTED
2020-04-27 16:55:19.967 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA
2020-04-27 16:55:19.969 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA
2020-04-27 16:55:19.971 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-27 16:55:19.972 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-27 16:55:19.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-27 16:55:19.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:19.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:19.978 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [ABORTED] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:19.979 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-27 16:55:19.981 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-27 16:55:19.984 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-27 16:55:22.565 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8B 01 02 FB 98
2020-04-27 16:55:22.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=139, payload=8B 01 02 FB
2020-04-27 16:55:22.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=139, payload=8B 01 02 FB
2020-04-27 16:55:22.572 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [ABORTED] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:22.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-27 16:55:22.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 221: [ABORTED] priority=Immediate, requiresResponse=true, callback: 139
2020-04-27 16:55:22.577 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 221: (Callback 139)
2020-04-27 16:55:22.579 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-04-27 16:55:22.581 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 221: callback 139
2020-04-27 16:55:22.583 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=139, payload=8B 01 02 FB
2020-04-27 16:55:22.585 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 53: SendData Request. CallBack ID = 139, Status = Transmission complete, no ACK received(1)
2020-04-27 16:55:22.587 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 221: Transaction CANCELLED
2020-04-27 16:55:22.589 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 53: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-04-27 16:55:22.591 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: CANCEL while sending message. Requeueing - 1 attempts left!
2020-04-27 16:55:22.593 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 221: Transaction RESET with 1 retries remaining.
2020-04-27 16:55:22.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: Adding to device queue
2020-04-27 16:55:22.597 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: Added 221 to queue - size 23
2020-04-27 16:55:22.599 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-04-27 16:55:22.601 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 35 02 86 11 25 8C EC
2020-04-27 16:55:22.603 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 53: Sending REQUEST Message = 01 09 00 13 35 02 86 11 25 8C EC
2020-04-27 16:55:22.605 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-04-27 16:55:22.607 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-04-27 16:55:22.607 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 140
2020-04-27 16:55:22.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:22.609 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: TID 221: Transaction not completed
2020-04-27 16:55:22.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-04-27 16:55:22.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 140
2020-04-27 16:55:22.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-04-27 16:55:22.616 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-04-27 16:55:22.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-27 16:55:22.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-04-27 16:55:22.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:22.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:22.623 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 140
2020-04-27 16:55:22.625 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-04-27 16:55:22.627 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 221: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 140
2020-04-27 16:55:22.629 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-04-27 16:55:22.630 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 53: sentData successfully placed on stack.
2020-04-27 16:55:22.632 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 221: Advanced to WAIT_REQUEST
2020-04-27 16:55:22.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 53: TID 221: Transaction not completed
2020-04-27 16:55:22.634 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-04-27 16:55:22.636 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.

DaHarry · April 27, 2020, 7:53pm

Hi again, I have the same problem with a new Aeotec trisensor. I Can’t modify the Association Group. Is there any issues with the last Z-Wave Binding?

chris · April 28, 2020, 7:49am

The “Unknown association group” log entry likely indicates that the initialisation didn’t discover the devices features fully. You should try to do a reinitialisation of the device (there’s a parameter you can change to do this in the appropriate thing).

DaHarry · April 28, 2020, 11:53am

Hi Chris,
i have try to Reinitialisation both devices, without any luck.
With both Devices i can`t update the Association Group…
This issue i have since the Last Update to 2.5.4-1

chris · April 28, 2020, 1:25pm

Have you woken up the device so the initialisation completes? Can you provide the log showing the initialisation?

DaHarry · April 28, 2020, 1:53pm

I have woken up the Devices 20 - 30 times with no changes … I send you the Log as Message

chris · April 28, 2020, 1:58pm

From the log you’ve sent, I don’t see the device being woken up. Until you wake up the device, it will simply not initialise.

This is however a very short log (1 second long!!!) so maybe you have woken it elsewhere. Can I suggest to provide a full log of the initialisation?

DaHarry · April 28, 2020, 3:06pm

I Remove the Device an re add it to Openhab. The new Log File i send you shortly.

DaHarry · April 29, 2020, 8:30am

Hi Again,
i have one More new PIR that i chan add to OpenHab but the Association Group is not correct. Also i can’t change it …
Is this a problem with the Binding or do i have a problem with my System?

chris · April 29, 2020, 12:59pm

You generally shouldn’t need to change associations for the device to report to the binding - that is done automatically for all ZWave plus devices, or devices that are configured as such in the database. IF you want to set an association for direct devices then this should be ok through the UI.

From your log it looks like there is some sort of initialisation issue with the device but I really don’t have the time to look at it right now.

DaHarry · April 29, 2020, 1:10pm

Hi Chris, all three devices i have add to the Controles since the last Update does not report anything to the Controller. This is, why i would change the Association Group to report to the Controller. Currently new Devices are unusable with zwave …
Could i rollback the Z-Wave Binding zu the Last Release Version and where could i find them…?

chris · April 29, 2020, 2:15pm

I’m not really sure why your system would have this issue though. It is clearly otherwise working in the vast majority of cases. Unfortunately without logs to understand what your systems problem is I’m just speculating.

DaHarry · April 29, 2020, 3:20pm

Could i send you some other Logs?
Can i download an older version of z-Wave Binding anywere?
I have already cleared the cache, unfortunately without improvement.
something else strikes me, the new devices have no neighbors…

DaHarry · May 2, 2020, 6:37pm

Hi,
I have install the Z-Wave 2.5.5 snapshot Binding. Now the Association Group for the Popp Rain Sensor is stored correctly and I receive data. However my Coolcam Pir not work.

DaHarry · May 5, 2020, 8:26pm

Hi, with the last z-Wave binding Snapshot (2.5.5.202005041107) the Popp Z-Rain also has neighbors.