2020-11-24 18:14:54.995 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Configuration update received 2020-11-24 18:14:55.000 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Configuration update set group_1 to [] (EmptyList) 2020-11-24 18:14:55.003 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Association 1 consolidated to [] 2020-11-24 18:14:55.006 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Current members before update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1]] 2020-11-24 18:14:55.008 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Members after config update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[]] 2020-11-24 18:14:55.011 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Controller is master - forcing associations 2020-11-24 18:14:55.026 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Group is controller - forcing association 2020-11-24 18:14:55.034 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Members after controller update ZWaveAssociationGroup [index=1, name=null, profile1=null, profile2=null, associations=[node_1_1]] 2020-11-24 18:14:55.036 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Removing node_1 from association group 1 2020-11-24 18:14:55.040 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: Creating new message for application command ASSOCIATIONCMD_REMOVE group=1, node=1 2020-11-24 18:14:55.042 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.046 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 2020-11-24 18:14:55.049 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2020-11-24 18:14:55.052 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 1521 to queue - size 2 2020-11-24 18:14:55.055 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-24 18:14:55.060 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 13 04 85 04 01 01 25 A7 F3 2020-11-24 18:14:55.064 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0B 00 13 13 04 85 04 01 01 25 A7 F3 2020-11-24 18:14:55.068 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-24 18:14:55.070 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-24 18:14:55.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1521: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.076 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.076 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Adding node_1_1 to association group 1 2020-11-24 18:14:55.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1521: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-24 18:14:55.080 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.079 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: Creating new message for application command ASSOCIATIONCMD_SET, group=1, node=1 2020-11-24 18:14:55.082 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.082 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.086 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-24 18:14:55.085 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 2020-11-24 18:14:55.088 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2020-11-24 18:14:55.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 1522 to queue - size 2 2020-11-24 18:14:55.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.094 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.096 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1521: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.097 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A7 00 00 02 4E 2020-11-24 18:14:55.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.097 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: Creating new message for application command ASSOCIATIONCMD_GET group 1 2020-11-24 18:14:55.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1521: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.100 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02 2020-11-24 18:14:55.103 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Command Class COMMAND_CLASS_ASSOCIATION is NOT required to be secured 2020-11-24 18:14:55.102 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Adding to device queue 2020-11-24 18:14:55.109 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData successfully placed on stack. 2020-11-24 18:14:55.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1521: Advanced to WAIT_REQUEST 2020-11-24 18:14:55.111 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Added 1523 to queue - size 3 2020-11-24 18:14:55.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1521: Transaction not completed 2020-11-24 18:14:55.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02 2020-11-24 18:14:55.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.117 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1521: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.122 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1521: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 167 2020-11-24 18:14:55.124 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1521: (Callback 167) 2020-11-24 18:14:55.126 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-24 18:14:55.128 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1521: callback 167 2020-11-24 18:14:55.137 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=167, payload=A7 00 00 02 2020-11-24 18:14:55.140 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: SendData Request. CallBack ID = 167, Status = Transmission complete and ACK received(0) 2020-11-24 18:14:55.142 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.145 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1521: Transaction COMPLETED 2020-11-24 18:14:55.147 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Response processed after 77ms 2020-11-24 18:14:55.152 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1521: Transaction completed 2020-11-24 18:14:55.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:1521 DONE 2020-11-24 18:14:55.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-24 18:14:55.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-24 18:14:55.169 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0B 00 13 13 04 85 01 01 01 25 A8 F9 2020-11-24 18:14:55.173 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0B 00 13 13 04 85 01 01 01 25 A8 F9 2020-11-24 18:14:55.177 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-24 18:14:55.175 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-24 18:14:55.179 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.180 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1522: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1522: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.184 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-24 18:14:55.185 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-24 18:14:55.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.188 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1522: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1522: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.196 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.198 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData successfully placed on stack. 2020-11-24 18:14:55.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1522: Advanced to WAIT_REQUEST 2020-11-24 18:14:55.201 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A8 00 00 03 40 2020-11-24 18:14:55.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1522: Transaction not completed 2020-11-24 18:14:55.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 03 2020-11-24 18:14:55.207 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 03 2020-11-24 18:14:55.209 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1522: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1522: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 168 2020-11-24 18:14:55.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1522: (Callback 168) 2020-11-24 18:14:55.214 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-24 18:14:55.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1522: callback 168 2020-11-24 18:14:55.217 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=168, payload=A8 00 00 03 2020-11-24 18:14:55.219 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: SendData Request. CallBack ID = 168, Status = Transmission complete and ACK received(0) 2020-11-24 18:14:55.221 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.222 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1522: Transaction COMPLETED 2020-11-24 18:14:55.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Response processed after 44ms 2020-11-24 18:14:55.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1522: Transaction completed 2020-11-24 18:14:55.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:1522 DONE 2020-11-24 18:14:55.228 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-24 18:14:55.229 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-24 18:14:55.234 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 13 03 85 02 01 25 A9 FC 2020-11-24 18:14:55.237 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 19: Sending REQUEST Message = 01 0A 00 13 13 03 85 02 01 25 A9 FC 2020-11-24 18:14:55.239 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2020-11-24 18:14:55.241 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1523: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.241 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2020-11-24 18:14:55.243 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.244 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2020-11-24 18:14:55.246 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1523: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.247 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2020-11-24 18:14:55.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2020-11-24 18:14:55.249 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.250 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.252 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1523: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.256 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1523: [WAIT_RESPONSE] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.259 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2020-11-24 18:14:55.260 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: sentData successfully placed on stack. 2020-11-24 18:14:55.262 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1523: Advanced to WAIT_REQUEST 2020-11-24 18:14:55.264 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1523: Transaction not completed 2020-11-24 18:14:55.265 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 A9 00 00 02 40 2020-11-24 18:14:55.265 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.266 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2020-11-24 18:14:55.268 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02 2020-11-24 18:14:55.270 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02 2020-11-24 18:14:55.272 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1523: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.273 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2020-11-24 18:14:55.275 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1523: [WAIT_REQUEST] priority=Config, requiresResponse=true, callback: 169 2020-11-24 18:14:55.276 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 85 03 01 05 00 01 61 2020-11-24 18:14:55.277 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1523: (Callback 169) 2020-11-24 18:14:55.278 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2020-11-24 18:14:55.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1523: callback 169 2020-11-24 18:14:55.281 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=169, payload=A9 00 00 02 2020-11-24 18:14:55.282 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.283 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 19: SendData Request. CallBack ID = 169, Status = Transmission complete and ACK received(0) 2020-11-24 18:14:55.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1523: Advanced to WAIT_DATA 2020-11-24 18:14:55.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: TID 1523: Transaction not completed 2020-11-24 18:14:55.290 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-24 18:14:55.293 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE) 2020-11-24 18:14:55.294 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.295 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2020-11-24 18:14:55.296 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.298 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_ASSOCIATION V1 ASSOCIATIONCMD_REPORT 2020-11-24 18:14:55.299 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 has max associations 5 2020-11-24 18:14:55.300 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 includes the following nodes: 2020-11-24 18:14:55.302 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - Node 1 2020-11-24 18:14:55.303 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveAssociationEvent 2020-11-24 18:14:55.304 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0 2020-11-24 18:14:55.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2020-11-24 18:14:55.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1bae7d3. 2020-11-24 18:14:55.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1bae7d3. 2020-11-24 18:14:55.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: notifyTransactionResponse TID:1523 DONE 2020-11-24 18:14:55.313 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-24 18:14:55.314 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 85 03 01 05 00 01 61 2020-11-24 18:14:55.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-11-24 18:14:55.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-24 18:14:55.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.319 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-24 18:14:55.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-24 18:14:55.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE) 2020-11-24 18:14:55.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2020-11-24 18:14:55.330 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.332 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_ASSOCIATION V1 ASSOCIATIONCMD_REPORT 2020-11-24 18:14:55.333 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 has max associations 5 2020-11-24 18:14:55.335 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 includes the following nodes: 2020-11-24 18:14:55.336 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - Node 1 2020-11-24 18:14:55.338 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveAssociationEvent 2020-11-24 18:14:55.340 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0 2020-11-24 18:14:55.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2020-11-24 18:14:55.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1aab1b1. 2020-11-24 18:14:55.345 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-24 18:14:55.346 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-24 18:14:55.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.349 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2020-11-24 18:14:55.352 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 13 06 85 03 01 05 00 01 61 2020-11-24 18:14:55.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.359 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=19, callback=0, payload=00 13 06 85 03 01 05 00 01 2020-11-24 18:14:55.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2020-11-24 18:14:55.362 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Application Command Request (ALIVE:DONE) 2020-11-24 18:14:55.363 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: resetResendCount initComplete=true isDead=false 2020-11-24 18:14:55.365 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: Incoming command class COMMAND_CLASS_ASSOCIATION, endpoint 0 2020-11-24 18:14:55.367 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 19: SECURITY not supported 2020-11-24 18:14:55.368 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 19: Received COMMAND_CLASS_ASSOCIATION V1 ASSOCIATIONCMD_REPORT 2020-11-24 18:14:55.370 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 has max associations 5 2020-11-24 18:14:55.371 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - NODE 19: association group 1 includes the following nodes: 2020-11-24 18:14:55.373 [DEBUG] [ndclass.ZWaveAssociationCommandClass] - Node 1 2020-11-24 18:14:55.375 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got an event from Z-Wave network: ZWaveAssociationEvent 2020-11-24 18:14:55.376 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 19: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_ASSOCIATION, value=0 2020-11-24 18:14:55.378 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Commands processed 1. 2020-11-24 18:14:55.380 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 19: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1f2ef9a. 2020-11-24 18:14:55.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-24 18:14:55.382 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-24 18:14:55.384 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-24 18:14:55.385 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. ==> /var/log/openhab2/events.log <== 2020-11-24 18:14:55.131 [me.event.ThingUpdatedEvent] - Thing 'zwave:device:5727c0c5:node19' has been updated. 2020-11-24 18:14:55.133 [vent.ConfigStatusInfoEvent] - ConfigStatusInfo [configStatusMessages=[ConfigStatusMessage [parameterName=group_1, type=PENDING, messageKey=null, arguments=null, message=null, statusCode=null]]]