Ecolink Tilt-zwave2 No longer working

chris · July 5, 2021, 7:03pm

As above though - how is your device configured to send reports?

Duggem · July 5, 2021, 8:23pm

Parameter 2 does not seem to change. No matter what I try to set it to (1, 255, etc.) the ‘PENDING’ tag does not go away.

I’ve tried saving the change, and then waking up the sensor with the tamper switch, waking the sensor by tilting it, removing and replacing the battery, reinitializing the device (I can only do this once as this choice disappears after the first selection.)

Below are logs.

Change parameter 2 to 255, hit save, device is asleep:

2021-07-05 16:10:54.767 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 16:10:54.767 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-07-05 16:10:54.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 16:10:54.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:10:54.768 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:11:07.052 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update received
2021-07-05 16:11:07.055 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored config_1_1 to 0 (BigDecimal)
2021-07-05 16:11:07.056 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update set config_2_1 to 255 (BigDecimal)
2021-07-05 16:11:07.056 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 24: Creating new message for application command CONFIGURATION_SET
2021-07-05 16:11:07.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 16:11:07.057 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-07-05 16:11:07.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Bump transaction 172 priority from Config to Immediate
2021-07-05 16:11:07.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:11:07.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 172 to queue - size 8
2021-07-05 16:11:07.058 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:11:07.059 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 24: Creating new message for application command CONFIGURATIONCMD_GET
2021-07-05 16:11:07.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 16:11:07.059 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-07-05 16:11:07.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Bump transaction 173 priority from Config to Immediate
2021-07-05 16:11:07.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:11:07.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 173 to queue - size 9
2021-07-05 16:11:07.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored action_reinit to false (Boolean)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored wakeup_node to 1 (BigDecimal)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored wakeup_interval to 14400 (BigDecimal)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored group_1 to [controller] (ArrayList)
2021-07-05 16:11:07.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored node_id to 24 (BigDecimal)
2021-07-05 16:11:07.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored group_2 to [] (EmptyList)

Wake up sensor with tamper switch:

2021-07-05 16:11:07.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Bump transaction 173 priority from Config to Immediate
2021-07-05 16:11:07.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:11:07.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 173 to queue - size 9
2021-07-05 16:11:07.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored action_reinit to false (Boolean)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored wakeup_node to 1 (BigDecimal)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored wakeup_interval to 14400 (BigDecimal)
2021-07-05 16:11:07.060 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored group_1 to [controller] (ArrayList)
2021-07-05 16:11:07.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored node_id to 24 (BigDecimal)
2021-07-05 16:11:07.061 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Configuration update ignored group_2 to [] (EmptyList)
2021-07-05 16:12:20.875 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 18 02 84 07 B0 00 D8 
2021-07-05 16:12:20.877 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 02 84 07 B0 00 
2021-07-05 16:12:20.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 02 84 07 B0 00 
2021-07-05 16:12:20.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-07-05 16:12:20.878 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:GET_CONFIGURATION)
2021-07-05 16:12:20.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Is awake with 3 messages in the queue
2021-07-05 16:12:20.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Start sleep timer at 5000ms
2021-07-05 16:12:20.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2021-07-05 16:12:20.885 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 24: Node Status event - Node is AWAKE
2021-07-05 16:12:20.885 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.
2021-07-05 16:12:20.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@dd64c9.
2021-07-05 16:12:20.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 16:12:20.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 16:12:20.886 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:20.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:12:20.887 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 18 03 70 05 01 25 85 29 
2021-07-05 16:12:20.888 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 0A 00 13 18 03 70 05 01 25 85 29 
2021-07-05 16:12:20.888 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:20.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 158: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:20.889 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:20.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:20.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:20.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 158: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:20.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:20.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:20.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:20.896 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-07-05 16:12:20.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:20.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:20.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 158: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:20.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:20.898 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 158: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:20.899 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:20.899 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.
2021-07-05 16:12:20.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 158: Advanced to WAIT_REQUEST
2021-07-05 16:12:20.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 158: Transaction not completed
2021-07-05 16:12:20.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:20.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:20.996 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 85 00 00 0A 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 01 00 00 C2 
2021-07-05 16:12:20.999 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 0A 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 01 00 00 
2021-07-05 16:12:21.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 0A 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 01 00 00 
2021-07-05 16:12:21.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 158: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:21.000 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:21.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 158: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 133
2021-07-05 16:12:21.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 158: (Callback 133)
2021-07-05 16:12:21.002 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-07-05 16:12:21.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 158: callback 133
2021-07-05 16:12:21.003 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 0A 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 01 00 00 
2021-07-05 16:12:21.004 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 133, Status = Transmission complete and ACK received(0)
2021-07-05 16:12:21.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 158: Advanced to WAIT_DATA
2021-07-05 16:12:21.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 158: Transaction not completed
2021-07-05 16:12:21.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:21.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:21.037 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 18 03 22 02 00 B1 00 7A 
2021-07-05 16:12:21.038 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 22 02 00 B1 00 
2021-07-05 16:12:21.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 22 02 00 B1 00 
2021-07-05 16:12:21.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-07-05 16:12:21.040 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:GET_CONFIGURATION)
2021-07-05 16:12:21.040 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_APPLICATION_STATUS, endpoint 0
2021-07-05 16:12:21.040 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 16:12:21.040 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_APPLICATION_STATUS V0 APPLICATION_REJECTED_REQUEST
2021-07-05 16:12:21.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.
2021-07-05 16:12:21.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@110e44f.
2021-07-05 16:12:21.041 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Command NOT verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@110e44f.
2021-07-05 16:12:21.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-07-05 16:12:21.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-07-05 16:12:21.042 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:21.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:23.380 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: WakeupTimerTask 3 Messages waiting, state GET_CONFIGURATION
2021-07-05 16:12:25.879 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: WakeupTimerTask 3 Messages waiting, state GET_CONFIGURATION
2021-07-05 16:12:25.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: No more messages, go back to sleep
2021-07-05 16:12:25.881 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 24: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2021-07-05 16:12:25.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 16:12:25.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2021-07-05 16:12:25.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1170b37
2021-07-05 16:12:25.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Bump transaction 174 priority from Immediate to Immediate
2021-07-05 16:12:25.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:12:25.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 174 to queue - size 9
2021-07-05 16:12:25.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:26.005 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 24: TID 158: Timeout at state WAIT_DATA. 2 retries remaining.
2021-07-05 16:12:26.006 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 158: Transaction CANCELLED
2021-07-05 16:12:26.006 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 16:12:26.006 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:158 CANCELLED
2021-07-05 16:12:26.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:12:26.007 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 158: Transaction event listener: DONE: CANCELLED -> 
2021-07-05 16:12:26.008 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: Node Init response (4) org.openhab.binding.zwave.internal.protocol.ZWaveTransactionResponse@52f6ab
2021-07-05 16:12:26.008 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 18 05 70 04 02 01 FF 25 95 C5 
2021-07-05 16:12:26.008 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: No data from device, but it was ACK'd. Possibly not supported? (Try 4)
2021-07-05 16:12:26.009 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: Node Init transaction retries exceeded
2021-07-05 16:12:26.009 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 0C 00 13 18 05 70 04 02 01 FF 25 95 C5 
2021-07-05 16:12:26.009 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: Node advancer: GET_CONFIGURATION - checking config_2_1 - config
2021-07-05 16:12:26.009 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: Node advancer: GET_CONFIGURATION - checking config_2_1 - index 2
2021-07-05 16:12:26.009 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:26.009 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 24: Creating new message for application command CONFIGURATIONCMD_GET
2021-07-05 16:12:26.010 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 24: ZWaveCommandClassTransactionPayload - send to node
2021-07-05 16:12:26.010 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.010 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 16:12:26.011 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-07-05 16:12:26.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1ce652b
2021-07-05 16:12:26.011 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:26.011 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Bump transaction 175 priority from Config to Immediate
2021-07-05 16:12:26.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:12:26.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:26.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Transaction already in queue - removed original
2021-07-05 16:12:26.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:26.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 175 to queue - size 8
2021-07-05 16:12:26.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:26.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:26.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:26.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:26.017 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-07-05 16:12:26.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:26.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 172: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.019 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.019 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.
2021-07-05 16:12:26.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 172: Advanced to WAIT_REQUEST
2021-07-05 16:12:26.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 172: Transaction not completed
2021-07-05 16:12:26.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:26.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:26.154 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 95 00 00 0E 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 02 00 00 D5 
2021-07-05 16:12:26.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=149, payload=95 00 00 0E 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 02 00 00 
2021-07-05 16:12:26.157 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=149, payload=95 00 00 0E 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 02 00 00 
2021-07-05 16:12:26.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 172: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:26.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 172: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 149
2021-07-05 16:12:26.158 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 172: (Callback 149)
2021-07-05 16:12:26.159 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-07-05 16:12:26.159 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 172: callback 149
2021-07-05 16:12:26.160 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=149, payload=95 00 00 0E 01 B3 7F 7F 7F 7F 01 01 03 0B 00 00 00 02 02 00 00 
2021-07-05 16:12:26.160 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 149, Status = Transmission complete and ACK received(0)
2021-07-05 16:12:26.161 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 172: Transaction COMPLETED
2021-07-05 16:12:26.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Response processed after 151ms
2021-07-05 16:12:26.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 172: Transaction completed
2021-07-05 16:12:26.161 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:172 DONE
2021-07-05 16:12:26.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 16:12:26.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:26.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:12:26.164 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 18 02 84 08 25 96 C0 
2021-07-05 16:12:26.164 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 09 00 13 18 02 84 08 25 96 C0 
2021-07-05 16:12:26.165 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:26.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 174: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:26.166 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:26.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:26.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:26.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 174: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:26.167 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:26.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:26.168 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:26.173 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-07-05 16:12:26.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.174 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.174 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 174: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:26.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:26.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 174: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:26.175 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:26.176 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.
2021-07-05 16:12:26.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 174: Advanced to WAIT_REQUEST
2021-07-05 16:12:26.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 174: Transaction not completed
2021-07-05 16:12:26.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:26.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:31.177 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 24: TID 174: Timeout at state WAIT_REQUEST. 3 retries remaining.
2021-07-05 16:12:31.178 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2021-07-05 16:12:31.179 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 174: Transaction ABORTED
2021-07-05 16:12:31.179 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2021-07-05 16:12:31.180 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2021-07-05 16:12:31.180 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:31.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:31.181 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:31.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:31.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:31.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 174: [ABORTED] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:31.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:31.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:31.183 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:33.672 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 96 01 02 EE 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0D 0A 18 EA 
2021-07-05 16:12:33.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=150, payload=96 01 02 EE 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0D 0A 18 
2021-07-05 16:12:33.674 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=150, payload=96 01 02 EE 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0D 0A 18 
2021-07-05 16:12:33.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 174: [ABORTED] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:33.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:33.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 174: [ABORTED] priority=Immediate, requiresResponse=true, callback: 150
2021-07-05 16:12:33.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 174: (Callback 150)
2021-07-05 16:12:33.675 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-07-05 16:12:33.675 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 174: callback 150
2021-07-05 16:12:33.676 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=150, payload=96 01 02 EE 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0D 0A 18 
2021-07-05 16:12:33.676 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 150, Status = Transmission complete, no ACK received(1)
2021-07-05 16:12:33.676 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: WAKE_UP_NO_MORE_INFORMATION. Treated as ACK.
2021-07-05 16:12:33.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 174: Transaction not completed
2021-07-05 16:12:33.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:33.677 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:43.179 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 24: TID 174: Timeout at state ABORTED. 3 retries remaining.
2021-07-05 16:12:43.180 [DEBUG] [sactionManager$ZWaveTransactionTimer] - TID 174: Transaction is current transaction, so clearing!!!!!
2021-07-05 16:12:43.180 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 174: Transaction CANCELLED
2021-07-05 16:12:43.181 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 16:12:43.181 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:174 CANCELLED
2021-07-05 16:12:43.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:12:43.182 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 174: Transaction event listener: DONE: CANCELLED -> 
2021-07-05 16:12:43.182 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Went to sleep COMPLETE
2021-07-05 16:12:43.183 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 18 03 70 05 02 25 97 38 
2021-07-05 16:12:43.183 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 24: Sending REQUEST Message = 01 0A 00 13 18 03 70 05 02 25 97 38 
2021-07-05 16:12:43.184 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:43.184 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:43.185 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:43.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:43.186 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:43.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:43.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:43.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:43.187 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:43.192 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2021-07-05 16:12:43.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:43.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:43.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:43.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:43.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 175: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:43.195 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
2021-07-05 16:12:43.195 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: sentData successfully placed on stack.
2021-07-05 16:12:43.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 175: Advanced to WAIT_REQUEST
2021-07-05 16:12:43.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 175: Transaction not completed
2021-07-05 16:12:43.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:43.196 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:48.195 [DEBUG] [sactionManager$ZWaveTransactionTimer] - NODE 24: TID 175: Timeout at state WAIT_REQUEST. 3 retries remaining.
2021-07-05 16:12:48.196 [DEBUG] [sactionManager$ZWaveTransactionTimer] - Aborting Transaction!
2021-07-05 16:12:48.196 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 175: Transaction ABORTED
2021-07-05 16:12:48.196 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 03 00 16 EA 
2021-07-05 16:12:48.197 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 255: Sending REQUEST Message = 01 03 00 16 EA 
2021-07-05 16:12:48.198 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-07-05 16:12:48.198 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:48.198 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-07-05 16:12:48.199 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:48.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-07-05 16:12:48.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 175: [ABORTED] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:48.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-07-05 16:12:48.200 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:48.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-07-05 16:12:49.822 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 97 01 02 97 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0A 0B 18 94 
2021-07-05 16:12:49.824 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=1, callback=151, payload=97 01 02 97 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0A 0B 18 
2021-07-05 16:12:49.825 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=1, callback=151, payload=97 01 02 97 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0A 0B 18 
2021-07-05 16:12:49.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 175: [ABORTED] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:49.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-07-05 16:12:49.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 175: [ABORTED] priority=Immediate, requiresResponse=true, callback: 151
2021-07-05 16:12:49.826 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 175: (Callback 151)
2021-07-05 16:12:49.827 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-07-05 16:12:49.827 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 175: callback 151
2021-07-05 16:12:49.827 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=1, callback=151, payload=97 01 02 97 00 7F 7F 7F 7F 7F 01 01 07 00 00 00 00 02 0A 0B 18 
2021-07-05 16:12:49.828 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 24: SendData Request. CallBack ID = 151, Status = Transmission complete, no ACK received(1)
2021-07-05 16:12:49.828 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 175: Transaction CANCELLED
2021-07-05 16:12:49.828 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 16:12:49.828 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: CANCEL while sending message. Requeueing - 2 attempts left!
2021-07-05 16:12:49.829 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 175: Transaction RESET with 2 retries remaining.
2021-07-05 16:12:49.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Adding to device queue
2021-07-05 16:12:49.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Added 175 to queue - size 7
2021-07-05 16:12:49.829 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 16:12:49.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 175: Transaction not completed
2021-07-05 16:12:49.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 16:12:49.830 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Duggem · July 5, 2021, 8:25pm

I’ve also restarted OH and get the same results.

chris · July 5, 2021, 9:07pm

It looks like the configuration was updated to 255 - how does the device now report?

Duggem · July 5, 2021, 9:17pm

2021-07-05 17:14:28.060 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false
2021-07-05 17:14:28.060 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 174: Transaction COMPLETED
2021-07-05 17:14:28.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Response processed after 81ms
2021-07-05 17:14:28.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: TID 174: Transaction completed
2021-07-05 17:14:28.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: notifyTransactionResponse TID:174 DONE
2021-07-05 17:14:28.062 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-07-05 17:14:28.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 174: Transaction event listener: DONE: DONE -> 
2021-07-05 17:14:28.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 17:14:28.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 17:14:28.063 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Went to sleep COMPLETE
2021-07-05 17:14:44.414 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 18 03 30 03 FF B1 00 96 
2021-07-05 17:14:44.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 30 03 FF B1 00 
2021-07-05 17:14:44.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 30 03 FF B1 00 
2021-07-05 17:14:44.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-07-05 17:14:44.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)
2021-07-05 17:14:44.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false
2021-07-05 17:14:44.417 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2021-07-05 17:14:44.418 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 17:14:44.418 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2021-07-05 17:14:44.418 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 24: Sensor Binary report, type=Unknown, value=255
2021-07-05 17:14:44.418 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2021-07-05 17:14:44.419 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2021-07-05 17:14:44.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.
2021-07-05 17:14:44.419 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@115427d.
2021-07-05 17:14:44.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 17:14:44.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 17:14:44.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 17:14:44.420 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-07-05 17:14:52.799 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0B 00 04 00 18 03 30 03 00 B1 00 69 
2021-07-05 17:14:52.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 30 03 00 B1 00 
2021-07-05 17:14:52.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=24, callback=0, payload=00 18 03 30 03 00 B1 00 
2021-07-05 17:14:52.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-07-05 17:14:52.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Application Command Request (ALIVE:DONE)
2021-07-05 17:14:52.802 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: resetResendCount initComplete=true isDead=false
2021-07-05 17:14:52.802 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2021-07-05 17:14:52.802 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 24: SECURITY not supported
2021-07-05 17:14:52.803 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 24: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2021-07-05 17:14:52.803 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 24: Sensor Binary report, type=Unknown, value=0
2021-07-05 17:14:52.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2021-07-05 17:14:52.803 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 24: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2021-07-05 17:14:52.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Commands processed 1.
2021-07-05 17:14:52.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 24: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@854799.
2021-07-05 17:14:52.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 17:14:52.804 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-07-05 17:14:52.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-07-05 17:14:52.805 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

nh905 · May 30, 2022, 7:38pm

Have there been any developments on this issue? I recently installed an Openhabian system on a Raspberry Pi 3 - OpenHAB version 3.2.0 and ZWave Binding 3.2.0. I am using an Aeotec Z-Stick Gen5. Since I do not have any switched Z-Wave devices yet, I installed an Aeotoc Range Extender 7. My first real Z-Wave devices are a pair of Ecolink TILTSWAVE2 sensors. I was successfully able to include them - I accepted all the defaults. I am seeing battery readings. Tilting the sensor generates “Sensor Binary report, type=Unknown, value=0/255” debug entries but the Door State continues to show NULL. I opened the sensor cover which caused OpenHAB to set the Tamper Alarm to Alarm and the Door State to Open, but subsequent tilts are not reflected in the Door State.

I have updated Configuration Parameter 2 to 255 and opened the sensor cover to pick up the change. I have restarted OpenHAB. Sensor tilts are still not showing up in the OpenHAB events.log. I found the .xml file associated with the node and will compare it to the .xml files posted earlier but am not sure whether I can safely make changes to my .xml file or what changes are required. I have also reviewed Z-Wave Garage Door Tilt Sensor - Deprecated Point? but am none the wiser.

If more information is required to resolve this issue, should I post additional information hear or start a new topic? I am happy to test and collect any required documentation.

apella12 · May 30, 2022, 10:12pm

Reading this a couple of times I can’t tell if people gave up or they made it work. The crux of the issue is that newer devices use Notifications (alarm), not the binary sensor to report OPEN/Close. The question bounced back and forth was whether the device would send the Notification, not the Binary, if parameter 2 was changed. I would read that if param 2 = 0 the binary would not be sent, just the Notification.

Post a debug level with Parameter 2 at 255 and do an open/close. Then have parameter 2 at zero (you will need to open the device and press the lever to wake and accept - no need to have the binding on Debug mode for this), then do another open/close in Debug.

To ensure we are talking about the same device, on the UI page for the device, expand the thing properties and provide the type/ID pair. I’m guessing it is 0001:0003.

Bob

nh905 · May 30, 2022, 11:28pm

@apella12 thanks for responding to quickly! The manufacturerRef=0001:0003.

Triggered sensor tilt and then back again with Parameter 2 (Sensor Binary Reports) set to 255.

2022-05-30 19:14:14.842 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 30 03 FF 39
2022-05-30 19:14:14.845 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 FF
2022-05-30 19:14:14.846 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 FF
2022-05-30 19:14:14.848 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-05-30 19:14:14.849 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-05-30 19:14:14.850 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-05-30 19:14:14.851 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2022-05-30 19:14:14.852 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-05-30 19:14:14.853 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2022-05-30 19:14:14.854 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 4: Sensor Binary report, type=Unknown, value=255
2022-05-30 19:14:14.855 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2022-05-30 19:14:14.855 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2022-05-30 19:14:14.856 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-05-30 19:14:14.857 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@45cd5b.
2022-05-30 19:14:14.858 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:14.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:14.859 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-05-30 19:14:14.860 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-05-30 19:14:19.562 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 30 03 00 C6
2022-05-30 19:14:19.567 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:14:19.568 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:14:19.570 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-05-30 19:14:19.571 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-05-30 19:14:19.573 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-05-30 19:14:19.574 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2022-05-30 19:14:19.576 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-05-30 19:14:19.577 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2022-05-30 19:14:19.579 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 4: Sensor Binary report, type=Unknown, value=0
2022-05-30 19:14:19.580 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2022-05-30 19:14:19.581 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2022-05-30 19:14:19.582 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-05-30 19:14:19.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1e27d55.
2022-05-30 19:14:19.584 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:19.586 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:19.586 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-05-30 19:14:19.588 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-05-30 19:14:19.695 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 30 03 00 C6
2022-05-30 19:14:19.697 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:14:19.699 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:14:19.701 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-05-30 19:14:19.702 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-05-30 19:14:19.704 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-05-30 19:14:19.706 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2022-05-30 19:14:19.707 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-05-30 19:14:19.709 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2022-05-30 19:14:19.710 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 4: Sensor Binary report, type=Unknown, value=0
2022-05-30 19:14:19.712 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2022-05-30 19:14:19.713 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2022-05-30 19:14:19.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-05-30 19:14:19.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c88a34.
2022-05-30 19:14:19.715 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:19.716 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:14:19.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-05-30 19:14:19.717 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Changed Parameter 2 to 0:

2022-05-30 19:15:58.479 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Configuration update received
2022-05-30 19:15:58.494 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Configuration update ignored config_1_1 to 0 (BigDecimal)
2022-05-30 19:15:58.496 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Configuration update set config_2_1 to 0 (BigDecimal)
2022-05-30 19:15:58.498 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 4: Creating new message for application command CONFIGURATION_SET

Tilted the sensor and then back again:

2022-05-30 19:22:37.547 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 30 03 FF 39
2022-05-30 19:22:37.550 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 FF
2022-05-30 19:22:37.551 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 FF
2022-05-30 19:22:37.552 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-05-30 19:22:37.553 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-05-30 19:22:37.554 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-05-30 19:22:37.555 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2022-05-30 19:22:37.556 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-05-30 19:22:37.556 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2022-05-30 19:22:37.557 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 4: Sensor Binary report, type=Unknown, value=255
2022-05-30 19:22:37.558 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2022-05-30 19:22:37.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255
2022-05-30 19:22:37.559 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-05-30 19:22:37.560 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@33c1cf.
2022-05-30 19:22:37.561 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:22:37.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:22:37.562 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-05-30 19:22:37.563 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2022-05-30 19:22:39.399 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 09 00 04 00 04 03 30 03 00 C6
2022-05-30 19:22:39.402 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:22:39.405 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=4, callback=0, payload=00 04 03 30 03 00
2022-05-30 19:22:39.406 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2022-05-30 19:22:39.407 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Application Command Request (ALIVE:DONE)
2022-05-30 19:22:39.407 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: resetResendCount initComplete=true isDead=false
2022-05-30 19:22:39.409 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: Incoming command class COMMAND_CLASS_SENSOR_BINARY, endpoint 0
2022-05-30 19:22:39.410 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 4: SECURITY not supported
2022-05-30 19:22:39.410 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 4: Received COMMAND_CLASS_SENSOR_BINARY V1 SENSOR_BINARY_REPORT
2022-05-30 19:22:39.411 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 4: Sensor Binary report, type=Unknown, value=0
2022-05-30 19:22:39.412 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got an event from Z-Wave network: ZWaveBinarySensorValueEvent
2022-05-30 19:22:39.413 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=0
2022-05-30 19:22:39.414 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Commands processed 1.
2022-05-30 19:22:39.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 4: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1c2907f.
2022-05-30 19:22:39.415 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:22:39.416 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2022-05-30 19:22:39.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2022-05-30 19:22:39.417 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.

Nothing related in events.log other than a ConfigStatusInfo type=PENDING around the time I opened the cover on the sensor.

mhilbush · May 30, 2022, 11:47pm

Normally, right after this line

2022-05-30 19:22:37.559 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 4: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SENSOR_BINARY, value=255

You would expect to see the binding report that it’s updating the channel. Something like this from my log file.

2022-05-29 11:45:54.867 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 26: Updating channel state zwave:device:e56f8b2a43:node26:sensor_binary to ON [OnOffType]

This suggests to me that possibly the sensor_binary channel is not linked to an item, because I think the binding only updates the channel if there’s an item linked to it…

You should be able see in MainUI whether or not the channel is linked to an item.

mhilbush · May 30, 2022, 11:57pm

BTW, I should add that I have one of these sensors and it has always worked. I stopped using it only because I was unhappy with the false reports that would occur whenever the wind blew hard against my garage door…

apella12 · May 31, 2022, 12:06am

It does appear to me that the device only provides a binary sensor report regardless of param 2 setting.

Second, changes to your xml file will not make a difference. What could need to be changed is the xml in the Zwave binding that is generated by the DB. I do not like making DB changes on the devices I do not own without some testing. And I’m confused why @mhilbush device works. The only difference seems to be his was done by textual configuration (see above). That said there is one line in the binding XML for the device that looks odd, maybe that is a problem. I could also try to change the xml in the binding to match the eco window door sensor, but both would require you to either modify a .jar or use maven to compile a binding? Do you feel either is possible? Also trying the textual configuration might be an option, but I have no experience there.

You can let me know in the morning (for me).

Bob

nh905 · May 31, 2022, 2:09pm

@mhilbush all three channels are linked to items. The odd thing is that when I triggered the Tamper Alarm, the Door State also changed from NULL to OPEN. It looks like the Zwave binding is ignoring SENSOR_BINARY and mapping the ALARM to both Tamper Alarm and Door State channels.

nh905 · May 31, 2022, 2:14pm

@apella12 I have searched for a definitive TILTZWAVE2 manual with no success. The ZWAVE Database entry for the v2 sensor links to the 2.5 manual which may explain the confusion.

I would rather avoid going the textual configuration route. I will read up on what it takes to modify the .jar file or recompile using Maven but expect it is something I can manage.

chips · May 31, 2022, 3:44pm

@nh905 I think you are on the right track! First a disclaimer: I only have an academic interest in this getting resolved. I’ve used this tilt sensor successfully for many years under openHAB v1, v2, and v3, but last month I moved it to Home Assistant (which I’m thrilled with). Also this thread it getting pretty long, and I made some comments above which I believe are still accurate, so I’ll be repeating some of them here.

I have used the Ecolink Tilt-Zwave2 successfully for many years.
I had the same exact issues and symptoms as described in this discussion when I upgraded to OH3.
The changes referred to above seem to have been made based on the assumption that the Tilt-Zwave2 provides the alarm command class for the door sensor. It does not.
Perhaps these changes were valid for other versions of this device, but they are not valid for this one.
This device has a tamper alarm (activated by opening the case) that has nothing to do with the open/closed door sensor.
The database is wrong. It references (and seems to rely on) the “Updated Manual” Tilt-ZWave-Plus-Manual-R1-04-021816kgs.pdf which is not for this device. The manual is for product type 4 (see bottom page 4). This discussion is about is product type 1, not product type 4.
I speculate that at some point TILTZWAVE2 (product type 1) was erroneously updated to reflect the characteristics from the manual for TILT-ZWAVE2.5-ECO (product type 4).

Last year when I saw this thread was going nowhere, I reverted the XML for this device and it worked great. These are the notes I made at the time:

Garage door not working because tiltzwave2_0_0.xml was changed six months ago by some misguided soul that seemd to think that the tamper alarm and door open were the same thing and made both of them an alarm. Reverted to the old version from github using:

$ jar -uf org.openhab.binding.zwave-3.0.1.jar OH-INF/thing/eco/tiltzwave2_0_0.xml

Copied .jar file to /usr/share/openhab/addons (next to .kar addons file) and it automagically loaded. Removed Thing and re-added it

I’m not necessarily recommending this approach to others. I’m only providing this information as a datapoint of what kept my system happy and hope it points you in the right direction for a real fix (updating the database or whatever).

apella12 · May 31, 2022, 5:45pm

Here are two different XML versions. The last digit (1 or 2) was just so I could keep them separate. They will need to be changed to “0” prior to merging (They need to overwrite the file that is in there now). It would be great to be able to run the same tests as above with each modified jar. For reference the current XML
tiltzwave2_0_0.xml (4.4 KB)

This XML was changed to use the Binary like the window/door 2.0 device. (I had the 2.5 eco versions of both the tilt and the door and configuration-wise they were identical so this should work)
tiltzwave2_0_1.xml (4.3 KB)
This version removed a property that looked odd and still tries to use the more modern Notification process. I’d be more comfortable with this fix, if it works.
tiltzwave2_0_2.xml (4.3 KB)

Since @chips basically outlined the jar modification command that might be the easiest path. This is an old thread, but has more detail (note replace ESH-INF with OH-INF like @chips did)

I’d grab the latest binding from here. Do the mods then follow the steps here to uninstall the UI binding and add to the Addons folder.

Good luck

Bob

chris · May 31, 2022, 7:57pm

I guess it’s not “wrong” as there seems to be two versions - so it seems to be correct for one version, and incompatible with the other - we need to have two database entries to resolve it.

Do these two versions have different version numbers? They should do - so we should be able to add both to the database which would solve the issue - but we need to know how to identify the two versions…

apella12 · May 31, 2022, 9:54pm

Since you are back I have a question re this line in the DB xml for the Tilt 2.0. Could this potentially be suppressing the device from sending the Notification (Alarm)? One of my XML’s above took it out (to see what would happen). But it is easier to just ask.

<property name="commandClass:COMMAND_CLASS_ALARM">getSupported=false</property>

Bob

chris · May 31, 2022, 9:56pm

No - changing the XML will not impact what the device does. If the device isn’t sending data, then it’s a device configuration issue most likely.

apella12 · May 31, 2022, 10:04pm

@nh905 Based this

Your task is cut in half, just try the first xml. assuming that works (I expect it to) we’ll have to figure out how to get two entries in the DB.

Bob

apella12 · May 31, 2022, 10:15pm

Thanks. I tried to follow isSupported logic (for version 2) in the Alarm CC but could not, so just figured it might be worth a try (with a willing tester) to keep the rest of the DB for the device as is. With my limited knowledge, it is a little unusual to see that line. It’s not in any of the other Eco devices I checked.

Anyway thanks again

Bob