2021-02-28 08:52:51.476 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=20, callback=132, payload=84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 2021-02-28 08:52:51.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-28 08:52:51.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 0 2021-02-28 08:52:51.487 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: null 2021-02-28 08:52:51.493 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=20, callback=132, payload=84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 2021-02-28 08:52:51.497 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update request. Node information received. Transaction null 2021-02-28 08:52:51.500 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_ZWAVEPLUS_INFO 2021-02-28 08:52:51.510 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_ZWAVEPLUS_INFO, endpoint 0 created 2021-02-28 08:52:51.514 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_ZWAVEPLUS_INFO. 2021-02-28 08:52:51.519 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_ZWAVEPLUS_INFO to the list of supported command classes. 2021-02-28 08:52:51.522 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_ASSOCIATION 2021-02-28 08:52:51.531 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_ASSOCIATION, endpoint 0 created 2021-02-28 08:52:51.535 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_ASSOCIATION. 2021-02-28 08:52:51.539 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_ASSOCIATION to the list of supported command classes. 2021-02-28 08:52:51.542 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION 2021-02-28 08:52:51.551 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION, endpoint 0 created 2021-02-28 08:52:51.554 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION. 2021-02-28 08:52:51.557 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_MULTI_CHANNEL_ASSOCIATION to the list of supported command classes. 2021-02-28 08:52:51.559 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_ASSOCIATION_GRP_INFO 2021-02-28 08:52:51.565 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_ASSOCIATION_GRP_INFO, endpoint 0 created 2021-02-28 08:52:51.569 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO. 2021-02-28 08:52:51.572 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_ASSOCIATION_GRP_INFO to the list of supported command classes. 2021-02-28 08:52:51.575 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE 2021-02-28 08:52:51.578 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_VERSION 2021-02-28 08:52:51.583 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_VERSION, endpoint 0 created 2021-02-28 08:52:51.587 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_VERSION. 2021-02-28 08:52:51.589 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_VERSION to the list of supported command classes. 2021-02-28 08:52:51.593 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-28 08:52:51.598 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 created 2021-02-28 08:52:51.602 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC. 2021-02-28 08:52:51.604 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_MANUFACTURER_SPECIFIC to the list of supported command classes. 2021-02-28 08:52:51.607 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_DEVICE_RESET_LOCALLY 2021-02-28 08:52:51.611 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_DEVICE_RESET_LOCALLY, endpoint 0 created 2021-02-28 08:52:51.614 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY. 2021-02-28 08:52:51.616 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_DEVICE_RESET_LOCALLY to the list of supported command classes. 2021-02-28 08:52:51.619 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_POWERLEVEL 2021-02-28 08:52:51.623 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_POWERLEVEL, endpoint 0 created 2021-02-28 08:52:51.626 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_POWERLEVEL. 2021-02-28 08:52:51.628 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_POWERLEVEL to the list of supported command classes. 2021-02-28 08:52:51.631 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_BATTERY 2021-02-28 08:52:51.635 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_BATTERY, endpoint 0 created 2021-02-28 08:52:51.638 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_BATTERY. 2021-02-28 08:52:51.640 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_BATTERY to the list of supported command classes. 2021-02-28 08:52:51.643 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_SECURITY_2 2021-02-28 08:52:51.645 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_ALARM 2021-02-28 08:52:51.656 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_ALARM, endpoint 0 created 2021-02-28 08:52:51.659 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_ALARM. 2021-02-28 08:52:51.663 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_ALARM to the list of supported command classes. 2021-02-28 08:52:51.665 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_WAKE_UP 2021-02-28 08:52:51.671 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_WAKE_UP, endpoint 0 created 2021-02-28 08:52:51.674 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_WAKE_UP. 2021-02-28 08:52:51.677 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_WAKE_UP to the list of supported command classes. 2021-02-28 08:52:51.679 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Creating new instance of command class COMMAND_CLASS_CONFIGURATION 2021-02-28 08:52:51.684 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Command class COMMAND_CLASS_CONFIGURATION, endpoint 0 created 2021-02-28 08:52:51.687 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update is adding command class COMMAND_CLASS_CONFIGURATION. 2021-02-28 08:52:51.690 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Adding command class COMMAND_CLASS_CONFIGURATION to the list of supported command classes. 2021-02-28 08:52:51.692 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_SUPERVISION 2021-02-28 08:52:51.696 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update - no transaction. 2021-02-28 08:52:51.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:51.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:52:51.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:52:51.970 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Is awake with 1 messages in the queue 2021-02-28 08:52:51.972 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Start sleep timer at 5000ms 2021-02-28 08:52:51.975 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2021-02-28 08:52:52.001 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 20: Node Status event - Node is AWAKE 2021-02-28 08:52:54.476 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF 2021-02-28 08:52:56.976 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: WakeupTimerTask 1 Messages waiting, state REQUEST_NIF 2021-02-28 08:52:56.979 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: No more messages, go back to sleep 2021-02-28 08:52:56.983 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 20: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2021-02-28 08:52:56.986 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:52:56.990 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2021-02-28 08:52:56.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@ca550b 2021-02-28 08:52:56.997 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Bump transaction 17039 priority from Immediate to Immediate 2021-02-28 08:52:57.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue 2021-02-28 08:52:57.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 17039 to queue - size 5 2021-02-28 08:52:57.008 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:52:57.014 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 00 60 14 8F 2021-02-28 08:52:57.021 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 04 00 60 14 8F 2021-02-28 08:52:57.027 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-28 08:52:57.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:52:57.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:52:57.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-28 08:52:57.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-28 08:52:57.041 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-28 08:52:57.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:57.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 16213: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.048 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:52:57.054 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 60 01 9B 2021-02-28 08:52:57.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.064 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.068 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 16213: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.071 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:52:57.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 16213: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.078 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=RequestNodeInfo[96], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.104 [DEBUG] [lmessage.RequestNodeInfoMessageClass] - Request node info successfully placed on stack. 2021-02-28 08:52:57.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 16213: Advanced to WAIT_DATA 2021-02-28 08:52:57.112 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 16213: Transaction not completed 2021-02-28 08:52:57.120 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:57.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:52:57.186 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 49 84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 5C 2021-02-28 08:52:57.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationUpdate[73], type=Request[0], dest=20, callback=132, payload=84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 2021-02-28 08:52:57.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationUpdate[73], type=Request[0], dest=20, callback=132, payload=84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 2021-02-28 08:52:57.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 16213: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:52:57.208 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 16213: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 16213: (Callback 0) 2021-02-28 08:52:57.213 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 16213: callback 0 2021-02-28 08:52:57.217 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=ApplicationUpdate[73], type=Request[0], dest=20, callback=132, payload=84 14 12 04 07 01 5E 85 8E 59 55 86 72 5A 73 80 9F 71 84 70 6C 2021-02-28 08:52:57.221 [DEBUG] [essage.ApplicationUpdateMessageClass] - NODE 20: Application update request. Node information received. Transaction TID 16213: [WAIT_DATA] priority=Immediate, requiresResponse=true, callback: 0 2021-02-28 08:52:57.223 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_TRANSPORT_SERVICE 2021-02-28 08:52:57.226 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_SECURITY_2 2021-02-28 08:52:57.228 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Unsupported command class COMMAND_CLASS_SUPERVISION 2021-02-28 08:52:57.245 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 16213: Transaction COMPLETED 2021-02-28 08:52:57.248 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Response processed after 203ms 2021-02-28 08:52:57.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 16213: Transaction completed 2021-02-28 08:52:57.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:16213 DONE 2021-02-28 08:52:57.257 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-28 08:52:57.257 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 16213: Transaction event listener: DONE: DONE -> 2021-02-28 08:52:57.260 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@362c9b 2021-02-28 08:52:57.262 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init transaction completed with response COMPLETE 2021-02-28 08:52:57.263 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:57.267 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:52:57.267 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer - advancing to SECURITY_REPORT 2021-02-28 08:52:57.270 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-28 08:52:57.271 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 84 08 25 FC A6 2021-02-28 08:52:57.272 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: SECURE command class not supported 2021-02-28 08:52:57.274 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer - advancing to MANUFACTURER 2021-02-28 08:52:57.276 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-28 08:52:57.276 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 84 08 25 FC A6 2021-02-28 08:52:57.278 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer: MANUFACTURER - send ManufacturerSpecific 2021-02-28 08:52:57.281 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-28 08:52:57.282 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 20: Creating new message for command MANUFACTURER_SPECIFIC_GET 2021-02-28 08:52:57.284 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:52:57.285 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: ZWaveCommandClassTransactionPayload - send to node 2021-02-28 08:52:57.289 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-28 08:52:57.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:52:57.292 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_MANUFACTURER_SPECIFIC is NOT required to be secured 2021-02-28 08:52:57.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@9b445 2021-02-28 08:52:57.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Bump transaction 17040 priority from Config to Immediate 2021-02-28 08:52:57.300 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-28 08:52:57.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17039: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue 2021-02-28 08:52:57.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:52:57.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 17040 to queue - size 4 2021-02-28 08:52:57.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17039: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-28 08:52:57.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:52:57.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17039: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:52:57.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17039: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.322 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:52:57.324 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack. 2021-02-28 08:52:57.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17039: Advanced to WAIT_REQUEST 2021-02-28 08:52:57.328 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17039: Transaction not completed 2021-02-28 08:52:57.330 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:57.332 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:52:57.335 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 FC 00 15 2021-02-28 08:52:57.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=252, payload=FC 00 2021-02-28 08:52:57.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=252, payload=FC 00 2021-02-28 08:52:57.348 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17039: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.351 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:52:57.354 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17039: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 252 2021-02-28 08:52:57.356 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17039: (Callback 252) 2021-02-28 08:52:57.359 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-28 08:52:57.361 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17039: callback 252 2021-02-28 08:52:57.363 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=252, payload=FC 00 2021-02-28 08:52:57.366 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 252, Status = Transmission complete and ACK received(0) 2021-02-28 08:52:57.368 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 17039: Transaction COMPLETED 2021-02-28 08:52:57.370 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Response processed after 68ms 2021-02-28 08:52:57.372 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17039: Transaction completed 2021-02-28 08:52:57.374 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:17039 DONE 2021-02-28 08:52:57.379 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-28 08:52:57.379 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17039: Transaction event listener: DONE: DONE -> 2021-02-28 08:52:57.381 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:52:57.382 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Went to sleep COMPLETE 2021-02-28 08:52:57.383 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:52:57.498 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Is awake with 1 messages in the queue 2021-02-28 08:52:57.501 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Start sleep timer at 5000ms 2021-02-28 08:52:57.503 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveNodeStatusEvent 2021-02-28 08:52:57.516 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 20: Node Status event - Node is AWAKE 2021-02-28 08:53:00.004 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: WakeupTimerTask 1 Messages waiting, state MANUFACTURER 2021-02-28 08:53:02.503 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: WakeupTimerTask 1 Messages waiting, state MANUFACTURER 2021-02-28 08:53:02.507 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: No more messages, go back to sleep 2021-02-28 08:53:02.509 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 20: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION 2021-02-28 08:53:02.512 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:53:02.515 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured 2021-02-28 08:53:02.519 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@323e7b 2021-02-28 08:53:02.522 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Bump transaction 17041 priority from Immediate to Immediate 2021-02-28 08:53:02.525 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue 2021-02-28 08:53:02.530 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 17041 to queue - size 5 2021-02-28 08:53:02.533 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:53:02.540 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 72 04 25 FD 5D 2021-02-28 08:53:02.545 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 72 04 25 FD 5D 2021-02-28 08:53:02.553 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-28 08:53:02.557 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.570 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-28 08:53:02.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.573 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-28 08:53:02.572 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-28 08:53:02.574 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17040: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 253 2021-02-28 08:53:02.576 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-28 08:53:02.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.579 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.587 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.590 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17040: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 253 2021-02-28 08:53:02.593 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.595 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17040: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 253 2021-02-28 08:53:02.600 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.603 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack. 2021-02-28 08:53:02.605 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17040: Advanced to WAIT_REQUEST 2021-02-28 08:53:02.608 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17040: Transaction not completed 2021-02-28 08:53:02.611 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.611 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 FD 00 14 2021-02-28 08:53:02.613 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=253, payload=FD 00 2021-02-28 08:53:02.622 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=253, payload=FD 00 2021-02-28 08:53:02.625 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17040: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 253 2021-02-28 08:53:02.627 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17040: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 253 2021-02-28 08:53:02.633 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17040: (Callback 253) 2021-02-28 08:53:02.636 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-28 08:53:02.638 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17040: callback 253 2021-02-28 08:53:02.641 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=253, payload=FD 00 2021-02-28 08:53:02.644 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 253, Status = Transmission complete and ACK received(0) 2021-02-28 08:53:02.647 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17040: Advanced to WAIT_DATA 2021-02-28 08:53:02.650 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17040: Transaction not completed 2021-02-28 08:53:02.652 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.654 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.747 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0E 00 04 00 14 08 72 05 01 38 00 01 00 03 A5 2021-02-28 08:53:02.754 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 08 72 05 01 38 00 01 00 03 2021-02-28 08:53:02.759 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 08 72 05 01 38 00 01 00 03 2021-02-28 08:53:02.761 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-28 08:53:02.763 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:MANUFACTURER) 2021-02-28 08:53:02.765 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_MANUFACTURER_SPECIFIC, endpoint 0 2021-02-28 08:53:02.767 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:53:02.770 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_MANUFACTURER_SPECIFIC V0 MANUFACTURER_SPECIFIC_REPORT 2021-02-28 08:53:02.772 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 20: Manufacturer ID = 0x138 2021-02-28 08:53:02.774 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 20: Device Type = 0x1 2021-02-28 08:53:02.776 [DEBUG] [WaveManufacturerSpecificCommandClass] - NODE 20: Device ID = 0x3 2021-02-28 08:53:02.778 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1. 2021-02-28 08:53:02.780 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1143264. 2021-02-28 08:53:02.782 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1143264. 2021-02-28 08:53:02.784 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:17040 DONE 2021-02-28 08:53:02.787 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-28 08:53:02.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17040: Transaction event listener: DONE: DONE -> 2021-02-28 08:53:02.789 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-28 08:53:02.790 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@10b26a0 2021-02-28 08:53:02.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-28 08:53:02.793 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init transaction completed with response COMPLETE 2021-02-28 08:53:02.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.796 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer - advancing to APP_VERSION 2021-02-28 08:53:02.796 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:53:02.798 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-28 08:53:02.800 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 84 08 25 FE A4 2021-02-28 08:53:02.802 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer: APP_VERSION - send VersionMessage 2021-02-28 08:53:02.804 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 84 08 25 FE A4 2021-02-28 08:53:02.804 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Creating new message for command VERSION_GET 2021-02-28 08:53:02.807 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: ZWaveCommandClassTransactionPayload - send to node 2021-02-28 08:53:02.810 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-28 08:53:02.813 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:53:02.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.816 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-28 08:53:02.817 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-28 08:53:02.818 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e34dc3 2021-02-28 08:53:02.819 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-28 08:53:02.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17041: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.822 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Bump transaction 17042 priority from Config to Immediate 2021-02-28 08:53:02.823 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue 2021-02-28 08:53:02.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17041: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 17042 to queue - size 4 2021-02-28 08:53:02.831 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.832 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-28 08:53:02.835 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.837 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17041: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.839 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.842 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17041: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.845 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.848 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack. 2021-02-28 08:53:02.850 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17041: Advanced to WAIT_REQUEST 2021-02-28 08:53:02.853 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17041: Transaction not completed 2021-02-28 08:53:02.854 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.864 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 FE 00 17 2021-02-28 08:53:02.868 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=254, payload=FE 00 2021-02-28 08:53:02.871 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=254, payload=FE 00 2021-02-28 08:53:02.874 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17041: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.876 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.879 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17041: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 254 2021-02-28 08:53:02.881 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17041: (Callback 254) 2021-02-28 08:53:02.884 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-28 08:53:02.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17041: callback 254 2021-02-28 08:53:02.889 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=254, payload=FE 00 2021-02-28 08:53:02.892 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 254, Status = Transmission complete and ACK received(0) 2021-02-28 08:53:02.895 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 17041: Transaction COMPLETED 2021-02-28 08:53:02.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Response processed after 77ms 2021-02-28 08:53:02.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17041: Transaction completed 2021-02-28 08:53:02.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:17041 DONE 2021-02-28 08:53:02.906 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-28 08:53:02.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17041: Transaction event listener: DONE: DONE -> 2021-02-28 08:53:02.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.909 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Went to sleep COMPLETE 2021-02-28 08:53:02.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:53:02.914 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 14 02 86 11 25 01 40 2021-02-28 08:53:02.919 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 20: Sending REQUEST Message = 01 09 00 13 14 02 86 11 25 01 40 2021-02-28 08:53:02.924 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06 2021-02-28 08:53:02.927 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.931 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 2021-02-28 08:53:02.939 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT 2021-02-28 08:53:02.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 17042: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.942 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 1<>127 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload= 2021-02-28 08:53:02.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17042: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK 2021-02-28 08:53:02.953 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.955 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17042: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17042: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.964 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 2021-02-28 08:53:02.967 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: sentData successfully placed on stack. 2021-02-28 08:53:02.970 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17042: Advanced to WAIT_REQUEST 2021-02-28 08:53:02.972 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17042: Transaction not completed 2021-02-28 08:53:02.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:02.977 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:02.980 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 05 00 13 01 00 E8 2021-02-28 08:53:02.985 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=1, payload=01 00 2021-02-28 08:53:02.988 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=1, payload=01 00 2021-02-28 08:53:02.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 17042: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1 2021-02-28 08:53:02.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 17042: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 1 2021-02-28 08:53:02.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 17042: (Callback 1) 2021-02-28 08:53:03.000 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match! 2021-02-28 08:53:03.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 17042: callback 1 2021-02-28 08:53:03.005 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=1, payload=01 00 2021-02-28 08:53:03.007 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 20: SendData Request. CallBack ID = 1, Status = Transmission complete and ACK received(0) 2021-02-28 08:53:03.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17042: Advanced to WAIT_DATA 2021-02-28 08:53:03.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: TID 17042: Transaction not completed 2021-02-28 08:53:03.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:03.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false. 2021-02-28 08:53:03.058 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 11 00 04 00 14 0B 86 12 06 06 07 0B 00 02 01 07 00 69 2021-02-28 08:53:03.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0B 86 12 06 06 07 0B 00 02 01 07 00 2021-02-28 08:53:03.074 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=20, callback=0, payload=00 14 0B 86 12 06 06 07 0B 00 02 01 07 00 2021-02-28 08:53:03.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null 2021-02-28 08:53:03.079 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Application Command Request (ALIVE:APP_VERSION) 2021-02-28 08:53:03.082 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Incoming command class COMMAND_CLASS_VERSION, endpoint 0 2021-02-28 08:53:03.084 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:53:03.086 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 20: Received COMMAND_CLASS_VERSION V0 VERSION_REPORT 2021-02-28 08:53:03.089 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Process Version Report 2021-02-28 08:53:03.092 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Library Type = 6 (Routing Slave) 2021-02-28 08:53:03.095 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Protocol Version = 6.7 2021-02-28 08:53:03.097 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Application Version = 11.0 2021-02-28 08:53:03.100 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Hardware Version = 2 2021-02-28 08:53:03.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Commands processed 1. 2021-02-28 08:53:03.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f9909. 2021-02-28 08:53:03.107 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14f9909. 2021-02-28 08:53:03.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: notifyTransactionResponse TID:17042 DONE 2021-02-28 08:53:03.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 17042: Transaction event listener: DONE: DONE -> 2021-02-28 08:53:03.113 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2021-02-28 08:53:03.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2021-02-28 08:53:03.115 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init response (0) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@e0e390 2021-02-28 08:53:03.119 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node Init transaction completed with response COMPLETE 2021-02-28 08:53:03.119 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2021-02-28 08:53:03.121 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer - advancing to DISCOVERY_COMPLETE 2021-02-28 08:53:03.121 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2021-02-28 08:53:03.123 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false. 2021-02-28 08:53:03.123 [DEBUG] [wave.discovery.ZWaveDiscoveryService] - NODE 20: Device discovery completed 2021-02-28 08:53:03.218 [WARN ] [wave.discovery.ZWaveDiscoveryService] - NODE 20: Device discovery could not resolve to a thingType! 0138:0001:0003::11.0 2021-02-28 08:53:03.245 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-28 08:53:03.248 [DEBUG] [l.initialization.ZWaveNodeSerializer] - NODE 20: Serialise aborted as static stages not complete 2021-02-28 08:53:03.251 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer - advancing to VERSION 2021-02-28 08:53:03.254 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 20: Got an event from Z-Wave network: ZWaveInitializationStateEvent 2021-02-28 08:53:03.275 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer: VERSION - thing is null! 2021-02-28 08:53:03.278 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer: VERSION - checking COMMAND_CLASS_MANUFACTURER_SPECIFIC, version is 0 2021-02-28 08:53:03.280 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: Node advancer: VERSION - queued COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-28 08:53:03.282 [DEBUG] [ommandclass.ZWaveVersionCommandClass] - NODE 20: Creating new message for application command VERSION_COMMAND_CLASS_GET command class COMMAND_CLASS_MANUFACTURER_SPECIFIC 2021-02-28 08:53:03.285 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 20: ZWaveCommandClassTransactionPayload - send to node 2021-02-28 08:53:03.287 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: SECURITY not supported 2021-02-28 08:53:03.289 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 20: Command Class COMMAND_CLASS_VERSION is NOT required to be secured 2021-02-28 08:53:03.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1e2d655 2021-02-28 08:53:03.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Bump transaction 17043 priority from Config to Immediate 2021-02-28 08:53:03.297 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Adding to device queue 2021-02-28 08:53:03.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 20: Added 17043 to queue - size 4 2021-02-28 08:53:03.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.