13:51:42.076 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update received 13:51:42.088 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_30_1 to 63 (BigDecimal) 13:51:42.101 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_31_1 to 0 (BigDecimal) 13:51:42.123 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored binding_cmdrepollperiod to 1500 (BigDecimal) 13:51:42.133 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_32_2 to 600 (BigDecimal) 13:51:42.148 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_11_1 to 15 (BigDecimal) 13:51:42.166 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_10_1 to 80 (BigDecimal) 13:51:42.196 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_50_1 to 3 (BigDecimal) 13:51:42.231 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored group_1 to controller (String) 13:51:42.262 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update set group_3 to null (null) 13:51:42.289 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.299 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.318 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.344 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.354 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_REMOVE group=3, node=all 13:51:42.367 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:42.376 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:42.385 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:42.409 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 836 to queue - size 6 13:51:42.426 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:42.438 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 04 03 25 B9 FE 13:51:42.449 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 04 03 25 B9 FE 13:51:42.459 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:42.461 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:42.470 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 836: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 185 13:51:42.479 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:42.492 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:42.500 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 185 13:51:42.495 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 3 13:51:42.493 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:42.509 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:42.524 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:42.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.530 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:42.550 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 B9 00 00 04 56 13:51:42.548 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:42.556 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:42.567 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 04 13:51:42.571 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:42.571 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.582 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 837 to queue - size 6 13:51:42.586 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 185 13:51:42.591 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:42.617 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:42.617 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update set group_2 to null (null) 13:51:42.623 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 836: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 185 13:51:42.629 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.635 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.639 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.646 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:42.656 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 836: Advanced to WAIT_REQUEST 13:51:42.650 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.665 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 836: Transaction not completed 13:51:42.670 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:42.680 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_REMOVE group=2, node=all 13:51:42.676 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 04 13:51:42.690 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:42.701 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 836: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 185 13:51:42.706 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:42.706 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:42.712 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 836: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 185 13:51:42.730 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 836: (Callback 185) 13:51:42.737 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:42.743 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 836: callback 185 13:51:42.750 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=185, payload=B9 00 00 04 13:51:42.750 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:42.757 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 185, Status = Transmission complete and ACK received(0) 13:51:42.761 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 838 to queue - size 7 13:51:42.773 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:42.767 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:42.780 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 2 13:51:42.795 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 836: Transaction COMPLETED 13:51:42.799 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:42.805 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 334ms 13:51:42.809 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:42.815 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 836: Transaction completed 13:51:42.818 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:42.823 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:836 DONE 13:51:42.829 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 839 to queue - size 8 13:51:42.835 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:42.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:42.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:42.859 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 02 03 25 BA FB 13:51:42.868 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 BA FB 13:51:42.874 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:42.876 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:42.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 837: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 186 13:51:42.884 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:42.889 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_reinit to false (Boolean) 13:51:42.889 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:42.897 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_12_2 to 30 (BigDecimal) 13:51:42.894 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:42.902 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:42.909 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_13_2 to 10 (BigDecimal) 13:51:42.917 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.915 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 186 13:51:42.928 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BA 00 00 02 53 13:51:42.922 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_14_2 to 3600 (BigDecimal) 13:51:42.937 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Request[0], dest=0, callback=186, payload=BA 00 00 02 13:51:42.929 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:42.939 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_15_1 to 0 (BigDecimal) 13:51:42.951 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.947 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 85 03 03 0A 00 7F 13:51:42.959 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 186 13:51:42.955 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_42_1 to 0 (BigDecimal) 13:51:42.966 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:42.971 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_20_1 to 0 (BigDecimal) 13:51:42.970 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 03 0A 00 13:51:42.980 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_40_2 to 25000 (BigDecimal) 13:51:42.975 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 837: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 186 13:51:42.989 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_41_1 to 1 (BigDecimal) 13:51:42.993 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:42.997 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_21_2 to 300 (BigDecimal) 13:51:43.002 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:43.007 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_43_1 to 2 (BigDecimal) 13:51:43.010 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 837: Advanced to WAIT_REQUEST 13:51:43.015 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_failed to false (Boolean) 13:51:43.019 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 837: Transaction not completed 13:51:43.025 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_remove to false (Boolean) 13:51:43.036 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=186, payload=BA 00 00 02 13:51:43.052 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 837: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 186 13:51:43.052 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored binding_pollperiod to 86400 (BigDecimal) 13:51:43.056 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:43.062 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_heal to false (Boolean) 13:51:43.066 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 837: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 186 13:51:43.070 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_1_1 to 0 (BigDecimal) 13:51:43.077 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 837: (Callback 186) 13:51:43.080 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_2_1 to 1 (BigDecimal) 13:51:43.088 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:43.097 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_22_2 to 500 (BigDecimal) 13:51:43.100 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 837: callback 186 13:51:43.115 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=186, payload=BA 00 00 02 13:51:43.122 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 186, Status = Transmission complete and ACK received(0) 13:51:43.129 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:43.110 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_23_1 to 6 (BigDecimal) 13:51:43.137 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 837: Advanced to WAIT_DATA 13:51:43.145 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_3_2 to 0 (BigDecimal) 13:51:43.150 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 837: Transaction not completed 13:51:43.163 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 03 0A 00 13:51:43.167 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_24_2 to 255 (BigDecimal) 13:51:43.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 13:51:43.187 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored node_id to 5 (BigDecimal) 13:51:43.195 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 13:51:43.213 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:43.227 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 13:51:43.233 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:43.241 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 13:51:43.250 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 3 has max associations 10 13:51:43.319 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 3 includes the following nodes: 13:51:43.325 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAssociationEvent 13:51:43.336 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 13:51:43.344 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 13:51:43.349 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d831c3. 13:51:43.354 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@d831c3. 13:51:43.360 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:837 DONE 13:51:43.364 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:43.368 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 13:51:43.370 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 13:51:43.373 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:43.376 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:43.380 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 04 02 25 BB FD 13:51:43.384 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 04 02 25 BB FD 13:51:43.399 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:43.403 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 838: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 187 13:51:43.402 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:43.413 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:43.425 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:43.425 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:43.431 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 187 13:51:43.438 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.439 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:43.446 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BB 00 00 02 52 13:51:43.446 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.453 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=187, payload=BB 00 00 02 13:51:43.454 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 187 13:51:43.460 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:43.462 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 838: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 187 13:51:43.466 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.473 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:43.477 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 838: Advanced to WAIT_REQUEST 13:51:43.481 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 838: Transaction not completed 13:51:43.487 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=187, payload=BB 00 00 02 13:51:43.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 838: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 187 13:51:43.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:43.506 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 838: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 187 13:51:43.511 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 838: (Callback 187) 13:51:43.517 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:43.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 838: callback 187 13:51:43.528 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=187, payload=BB 00 00 02 13:51:43.532 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 187, Status = Transmission complete and ACK received(0) 13:51:43.539 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:43.544 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 838: Transaction COMPLETED 13:51:43.548 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 145ms 13:51:43.551 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 838: Transaction completed 13:51:43.555 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:838 DONE 13:51:43.559 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:43.563 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:43.568 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:43.573 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 02 02 25 BC FC 13:51:43.578 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 BC FC 13:51:43.583 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:43.588 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 839: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 188 13:51:43.586 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:43.597 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:43.603 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:43.604 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:43.607 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 839: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 188 13:51:43.613 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.614 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:43.620 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BC 00 00 02 55 13:51:43.621 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.626 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 02 13:51:43.627 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 839: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 188 13:51:43.633 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 85 03 02 0A 00 7E 13:51:43.634 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:43.641 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 02 0A 00 13:51:43.647 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 839: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 188 13:51:43.655 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:43.660 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:43.665 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 839: Advanced to WAIT_REQUEST 13:51:43.670 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 839: Transaction not completed 13:51:43.674 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 02 13:51:43.679 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 839: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 188 13:51:43.685 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:43.690 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 839: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 188 13:51:43.695 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 839: (Callback 188) 13:51:43.700 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:43.704 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 839: callback 188 13:51:43.709 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=188, payload=BC 00 00 02 13:51:43.713 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 188, Status = Transmission complete and ACK received(0) 13:51:43.719 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:43.723 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 839: Advanced to WAIT_DATA 13:51:43.730 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 839: Transaction not completed 13:51:43.736 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 02 0A 00 13:51:43.742 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 13:51:43.747 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 13:51:43.754 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:43.759 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 13:51:43.765 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:43.772 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 13:51:43.779 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 2 has max associations 10 13:51:43.784 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 2 includes the following nodes: 13:51:43.790 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAssociationEvent 13:51:43.795 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 13:51:43.802 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 13:51:43.808 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fd7679. 13:51:43.814 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1fd7679. 13:51:43.820 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:839 DONE 13:51:43.825 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:43.830 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 13:51:43.834 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 13:51:43.839 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:43.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:58.321 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update received 13:51:58.328 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_30_1 to 63 (BigDecimal) 13:51:58.333 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_31_1 to 0 (BigDecimal) 13:51:58.338 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored binding_cmdrepollperiod to 1500 (BigDecimal) 13:51:58.344 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_32_2 to 600 (BigDecimal) 13:51:58.350 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_11_1 to 15 (BigDecimal) 13:51:58.355 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_10_1 to 80 (BigDecimal) 13:51:58.366 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_50_1 to 3 (BigDecimal) 13:51:58.373 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored group_1 to controller (String) 13:51:58.381 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update set group_3 to null (null) 13:51:58.389 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.401 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.409 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.418 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=3, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.426 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_REMOVE group=3, node=all 13:51:58.432 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:58.439 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:58.445 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:58.452 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 840 to queue - size 6 13:51:58.459 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:58.467 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 04 03 25 BD FA 13:51:58.475 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 04 03 25 BD FA 13:51:58.483 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:58.481 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:58.489 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:58.494 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 840: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 189 13:51:58.506 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 3 13:51:58.501 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:58.499 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:58.521 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 840: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 189 13:51:58.511 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:58.532 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:58.536 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:58.539 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:58.528 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.543 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:58.555 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BD 00 00 03 55 13:51:58.557 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:58.563 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 03 13:51:58.557 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 841 to queue - size 6 13:51:58.584 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 840: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 189 13:51:58.595 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:58.606 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:58.606 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update set group_2 to null (null) 13:51:58.611 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 840: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 189 13:51:58.615 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.618 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.624 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Current Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.629 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:58.634 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.639 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 840: Advanced to WAIT_REQUEST 13:51:58.645 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: New Members ZWaveAssociationGroup [index=2, name=null, profile1=null, profile2=null, associations=[]] 13:51:58.649 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 840: Transaction not completed 13:51:58.654 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_REMOVE group=2, node=all 13:51:58.659 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 03 13:51:58.664 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:58.670 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 840: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 189 13:51:58.674 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:58.677 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:58.686 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 840: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 189 13:51:58.689 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 840: (Callback 189) 13:51:58.693 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:58.697 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 840: callback 189 13:51:58.702 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=189, payload=BD 00 00 03 13:51:58.705 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:58.706 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 189, Status = Transmission complete and ACK received(0) 13:51:58.712 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 842 to queue - size 7 13:51:58.717 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:58.720 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:58.733 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: Creating new message for application command ASSOCIATIONCMD_GET group 2 13:51:58.726 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 840: Transaction COMPLETED 13:51:58.742 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:58.751 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 257ms 13:51:58.756 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 13:51:58.760 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 840: Transaction completed 13:51:58.763 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Adding to device queue 13:51:58.767 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:840 DONE 13:51:58.776 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Added 843 to queue - size 8 13:51:58.782 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:58.787 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:58.793 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:58.798 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 02 03 25 BE FF 13:51:58.806 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 03 25 BE FF 13:51:58.811 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:58.813 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:58.817 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 841: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 190 13:51:58.821 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:58.826 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_reinit to false (Boolean) 13:51:58.828 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:58.827 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 13:51:58.832 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_12_2 to 30 (BigDecimal) 13:51:58.839 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:58.837 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.844 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_13_2 to 10 (BigDecimal) 13:51:58.845 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 841: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 190 13:51:58.852 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BE 00 00 03 56 13:51:58.855 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:58.854 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_14_2 to 3600 (BigDecimal) 13:51:58.862 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.864 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 03 13:51:58.867 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_15_1 to 0 (BigDecimal) 13:51:58.868 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 841: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 190 13:51:58.876 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_42_1 to 0 (BigDecimal) 13:51:58.873 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 85 03 03 0A 00 7F 13:51:58.888 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_20_1 to 0 (BigDecimal) 13:51:58.881 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:58.893 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 03 0A 00 13:51:58.902 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 841: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 190 13:51:58.897 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_40_2 to 25000 (BigDecimal) 13:51:58.936 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_41_1 to 1 (BigDecimal) 13:51:58.930 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:58.950 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_21_2 to 300 (BigDecimal) 13:51:58.956 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:58.966 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_43_1 to 2 (BigDecimal) 13:51:58.972 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 841: Advanced to WAIT_REQUEST 13:51:58.978 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_failed to false (Boolean) 13:51:58.983 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 841: Transaction not completed 13:51:58.995 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_remove to false (Boolean) 13:51:59.011 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored binding_pollperiod to 86400 (BigDecimal) 13:51:59.000 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 03 13:51:59.030 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 841: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 190 13:51:59.024 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored action_heal to false (Boolean) 13:51:59.037 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:59.046 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_1_1 to 0 (BigDecimal) 13:51:59.054 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 841: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 190 13:51:59.059 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_2_1 to 1 (BigDecimal) 13:51:59.065 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 841: (Callback 190) 13:51:59.072 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_22_2 to 500 (BigDecimal) 13:51:59.093 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_23_1 to 6 (BigDecimal) 13:51:59.082 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:59.101 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_3_2 to 0 (BigDecimal) 13:51:59.117 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored config_24_2 to 255 (BigDecimal) 13:51:59.112 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 841: callback 190 13:51:59.127 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Configuration update ignored node_id to 5 (BigDecimal) 13:51:59.132 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=190, payload=BE 00 00 03 13:51:59.142 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 190, Status = Transmission complete and ACK received(0) 13:51:59.147 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:59.151 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 841: Advanced to WAIT_DATA 13:51:59.171 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 841: Transaction not completed 13:51:59.176 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 03 0A 00 13:51:59.182 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 13:51:59.188 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 13:51:59.193 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:59.199 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 13:51:59.204 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:59.208 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 13:51:59.212 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 3 has max associations 10 13:51:59.217 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 3 includes the following nodes: 13:51:59.220 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAssociationEvent 13:51:59.223 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 13:51:59.226 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 13:51:59.229 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@52f98b. 13:51:59.232 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@52f98b. 13:51:59.237 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:841 DONE 13:51:59.243 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:59.251 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 13:51:59.258 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 13:51:59.262 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:59.268 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:59.273 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 04 02 25 BF F9 13:51:59.279 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 04 02 25 BF F9 13:51:59.284 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:59.286 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:59.289 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 842: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 191 13:51:59.291 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:59.296 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:59.297 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:59.300 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 842: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 191 13:51:59.305 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.314 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:59.319 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 BF 00 00 02 56 13:51:59.323 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.327 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 02 13:51:59.331 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 842: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 191 13:51:59.344 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:59.348 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 842: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 191 13:51:59.352 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.358 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:59.363 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 842: Advanced to WAIT_REQUEST 13:51:59.368 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 842: Transaction not completed 13:51:59.374 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 02 13:51:59.379 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 842: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 191 13:51:59.384 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:59.389 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 842: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 191 13:51:59.394 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 842: (Callback 191) 13:51:59.399 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:59.405 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 842: callback 191 13:51:59.409 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=191, payload=BF 00 00 02 13:51:59.414 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 191, Status = Transmission complete and ACK received(0) 13:51:59.419 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:59.423 [DEBUG] [ve.internal.protocol.ZWaveTransaction] - TID 842: Transaction COMPLETED 13:51:59.427 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Response processed after 138ms 13:51:59.432 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 842: Transaction completed 13:51:59.438 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:842 DONE 13:51:59.445 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:59.450 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:59.455 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 13:51:59.460 [DEBUG] [zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 05 03 85 02 02 25 C0 80 13:51:59.467 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 85 02 02 25 C0 80 13:51:59.472 [DEBUG] [ding.zwave.handler.ZWaveSerialHandler] - Message SENT 13:51:59.474 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 13:51:59.476 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 843: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 192 13:51:59.481 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:59.488 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 13:51:59.488 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 13:51:59.491 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 843: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 192 13:51:59.495 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.498 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg: ACK 13:51:59.502 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 C0 00 00 02 29 13:51:59.504 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.509 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 02 13:51:59.511 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 843: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 192 13:51:59.515 [DEBUG] [ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 05 05 85 03 02 0A 00 7E 13:51:59.517 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:59.522 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 02 0A 00 13:51:59.525 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 843: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 192 13:51:59.532 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 13:51:59.538 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: sentData successfully placed on stack. 13:51:59.542 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 843: Advanced to WAIT_REQUEST 13:51:59.546 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 843: Transaction not completed 13:51:59.550 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (1): Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 02 13:51:59.554 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction TID 843: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 192 13:51:59.557 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 13:51:59.562 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Last transaction: TID 843: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 192 13:51:59.566 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Checking TID 843: (Callback 192) 13:51:59.571 [DEBUG] [l.serialmessage.ZWaveCommandProcessor] - Callback match! 13:51:59.574 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Correlated to TID 843: callback 192 13:51:59.578 [DEBUG] [ave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=192, payload=C0 00 00 02 13:51:59.583 [DEBUG] [ol.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 192, Status = Transmission complete and ACK received(0) 13:51:59.587 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:59.591 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - TID 843: Advanced to WAIT_DATA 13:51:59.595 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: TID 843: Transaction not completed 13:51:59.600 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=5, callback=0, payload=00 05 05 85 03 02 0A 00 13:51:59.604 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - lastTransaction null 13:51:59.607 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Application Command Request (ALIVE:DONE) 13:51:59.610 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: resetResendCount initComplete=true isDead=false 13:51:59.614 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 13:51:59.619 [DEBUG] [ing.zwave.internal.protocol.ZWaveNode] - NODE 5: SECURITY NOT required on COMMAND_CLASS_ASSOCIATION 13:51:59.623 [DEBUG] [otocol.commandclass.ZWaveCommandClass] - NODE 5: Received COMMAND_CLASS_ASSOCIATION V2 ASSOCIATIONCMD_REPORT 13:51:59.627 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 2 has max associations 10 13:51:59.630 [DEBUG] [andclass.ZWaveAssociationCommandClass] - NODE 5: association group 2 includes the following nodes: 13:51:59.634 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveAssociationEvent 13:51:59.637 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got a value event from Z-Wave network, endpoint = 0, command class = COMMAND_CLASS_ASSOCIATION, value = 0 13:51:59.641 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Commands processed 1. 13:51:59.646 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e7e803. 13:51:59.651 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e7e803. 13:51:59.655 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - NODE 5: notifyTransactionResponse TID:843 DONE 13:51:59.659 [DEBUG] [nding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 13:51:59.664 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 13:51:59.668 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 13:51:59.674 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 13:51:59.683 [DEBUG] [rnal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.