In order to further narrow down the problem, here is some more logging, on step 2 (= enter -5 and press “Save”).
Note however that for the “CONFIGURATION_REPORT” the Value=256
might be misleading; at 16:06:54.771
the value is -1279
according to the log output at 16:06:54.781
.
-1279
corresponds to "FB01"
which eqals to -5
(high byte = Temperature Calibration Offset) and 1 (low byte = Temperature Calibration Unit). I.e. the value was correctly set in the device.
Well, seems the "FB"
is interpreted as 251
instead of -5
. But the update of the displayed value is only done lateron in Step 3 (when -5 becomes 251).
Does this provide enough inside for problem resolution?
Here is the log output:
2021-01-04 16:03:28.475 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Configuration update set config_201_2_0000FF00 to -5 (BigDecimal)
2021-01-04 16:03:28.476 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Set sub-parameter 201 from -5 / 0000FF00
2021-01-04 16:03:28.477 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Parameter 201 set value FFFFFFFB mask 0000FF00
2021-01-04 16:03:28.478 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 63: Creating new message for application command CONFIGURATIONCMD_GET
2021-01-04 16:03:28.479 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:03:28.480 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-01-04 16:03:28.481 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Bump transaction 1196 priority from Config to Immediate
2021-01-04 16:03:28.482 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Adding to device queue
2021-01-04 16:03:28.483 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Added 1196 to queue - size 3
2021-01-04 16:03:28.484 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
[...]
2021-01-04 16:06:53.275 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 08 00 04 00 3F 02 84 07 4D
2021-01-04 16:06:53.276 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 02 84 07
2021-01-04 16:06:53.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 02 84 07
2021-01-04 16:06:53.278 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-01-04 16:06:53.279 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE)
2021-01-04 16:06:53.279 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:53.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Is awake with 1 messages in the queue
2021-01-04 16:06:53.280 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Start sleep timer at 1000ms
2021-01-04 16:06:53.281 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveNodeStatusEvent
2021-01-04 16:06:53.287 [DEBUG] [ve.internal.protocol.ZWaveController] - NODE 63: Node Status event - Node is AWAKE
2021-01-04 16:06:53.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1.
2021-01-04 16:06:53.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10d28da.
2021-01-04 16:06:53.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-04 16:06:53.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-04 16:06:53.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.292 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-04 16:06:53.293 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 3F 03 70 05 C9 25 8D CE
2021-01-04 16:06:53.294 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 63: Sending REQUEST Message = 01 0A 00 13 3F 03 70 05 C9 25 8D CE
2021-01-04 16:06:53.295 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-04 16:06:53.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.297 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-04 16:06:53.298 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:53.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:53.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-04 16:06:53.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.303 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-04 16:06:53.304 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:53.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1196: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.308 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.309 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: sentData successfully placed on stack.
2021-01-04 16:06:53.310 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1196: Advanced to WAIT_REQUEST
2021-01-04 16:06:53.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1196: Transaction not completed
2021-01-04 16:06:53.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.702 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8D 00 00 28 4E
2021-01-04 16:06:53.703 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 28
2021-01-04 16:06:53.704 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 28
2021-01-04 16:06:53.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1196: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.705 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:53.706 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1196: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 141
2021-01-04 16:06:53.707 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1196: (Callback 141)
2021-01-04 16:06:53.708 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-04 16:06:53.709 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1196: callback 141
2021-01-04 16:06:53.709 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=141, payload=8D 00 00 28
2021-01-04 16:06:53.710 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: SendData Request. CallBack ID = 141, Status = Transmission complete and ACK received(0)
2021-01-04 16:06:53.711 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:53.712 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1196: Advanced to WAIT_DATA
2021-01-04 16:06:53.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1196: Transaction not completed
2021-01-04 16:06:53.713 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.714 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.782 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: WakeupTimerTask 1 Messages waiting, state DONE
2021-01-04 16:06:53.861 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 3F 06 70 06 C9 02 00 01 72
2021-01-04 16:06:53.862 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 06 70 06 C9 02 00 01
2021-01-04 16:06:53.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 06 70 06 C9 02 00 01
2021-01-04 16:06:53.869 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-01-04 16:06:53.870 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE)
2021-01-04 16:06:53.870 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:53.871 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Incoming command class COMMAND_CLASS_CONFIGURATION, endpoint 0
2021-01-04 16:06:53.872 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:06:53.873 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 63: Received COMMAND_CLASS_CONFIGURATION V1 CONFIGURATIONCMD_REPORT
2021-01-04 16:06:53.873 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 63: Node configuration report, parameter = 201, value = 1, size = 2
2021-01-04 16:06:53.875 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
2021-01-04 16:06:53.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_CONFIGURATION, value=org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@14d72e6
2021-01-04 16:06:53.877 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Update CONFIGURATION 201/2 to 1
2021-01-04 16:06:53.878 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Updating sub-parameter 201 to 64257
2021-01-04 16:06:53.879 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Setting parameter 201 to 64257
2021-01-04 16:06:53.879 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 63: Creating new message for application command CONFIGURATION_SET
2021-01-04 16:06:53.880 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:06:53.881 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-01-04 16:06:53.882 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Bump transaction 1198 priority from Config to Immediate
2021-01-04 16:06:53.883 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Adding to device queue
2021-01-04 16:06:53.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Added 1198 to queue - size 3
2021-01-04 16:06:53.884 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.885 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 63: Creating new message for application command CONFIGURATIONCMD_GET
2021-01-04 16:06:53.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:06:53.886 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Command Class COMMAND_CLASS_CONFIGURATION is NOT required to be secured
2021-01-04 16:06:53.887 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Bump transaction 1199 priority from Config to Immediate
2021-01-04 16:06:53.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Adding to device queue
2021-01-04 16:06:53.888 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Added 1199 to queue - size 4
2021-01-04 16:06:53.889 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1.
2021-01-04 16:06:53.890 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f8ed3c.
2021-01-04 16:06:53.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f8ed3c.
2021-01-04 16:06:53.891 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: notifyTransactionResponse TID:1196 DONE
2021-01-04 16:06:53.892 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-04 16:06:53.893 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-01-04 16:06:53.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-04 16:06:53.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.894 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-04 16:06:53.895 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0D 00 13 3F 06 70 04 C9 02 FB 01 25 8E 36
2021-01-04 16:06:53.896 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 63: Sending REQUEST Message = 01 0D 00 13 3F 06 70 04 C9 02 FB 01 25 8E 36
2021-01-04 16:06:53.896 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-04 16:06:53.897 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1198: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:53.898 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-04 16:06:53.899 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:53.900 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:53.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1198: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:53.901 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-04 16:06:53.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.902 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:53.905 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-04 16:06:53.906 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.907 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1198: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:53.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:53.908 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1198: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:53.909 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:53.909 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: sentData successfully placed on stack.
2021-01-04 16:06:53.910 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1198: Advanced to WAIT_REQUEST
2021-01-04 16:06:53.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1198: Transaction not completed
2021-01-04 16:06:53.911 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:53.912 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:54.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: WakeupTimerTask 2 Messages waiting, state DONE
2021-01-04 16:06:54.282 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: No more messages, go back to sleep
2021-01-04 16:06:54.283 [DEBUG] [commandclass.ZWaveWakeUpCommandClass] - NODE 63: Creating new message for application command WAKE_UP_NO_MORE_INFORMATION
2021-01-04 16:06:54.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:06:54.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Command Class COMMAND_CLASS_WAKE_UP is NOT required to be secured
2021-01-04 16:06:54.285 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: sendTransaction org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@2587ee
2021-01-04 16:06:54.286 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Bump transaction 1200 priority from Immediate to Immediate
2021-01-04 16:06:54.287 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Adding to device queue
2021-01-04 16:06:54.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Added 1200 to queue - size 4
2021-01-04 16:06:54.288 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:54.297 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8E 00 00 28 4D
2021-01-04 16:06:54.299 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 28
2021-01-04 16:06:54.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 28
2021-01-04 16:06:54.300 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1198: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:54.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:54.301 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1198: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 142
2021-01-04 16:06:54.302 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1198: (Callback 142)
2021-01-04 16:06:54.302 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-04 16:06:54.303 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1198: callback 142
2021-01-04 16:06:54.304 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=142, payload=8E 00 00 28
2021-01-04 16:06:54.304 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: SendData Request. CallBack ID = 142, Status = Transmission complete and ACK received(0)
2021-01-04 16:06:54.305 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:54.305 [DEBUG] [e.internal.protocol.ZWaveTransaction] - TID 1198: Transaction COMPLETED
2021-01-04 16:06:54.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Response processed after 409ms
2021-01-04 16:06:54.306 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1198: Transaction completed
2021-01-04 16:06:54.307 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: notifyTransactionResponse TID:1198 DONE
2021-01-04 16:06:54.308 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-04 16:06:54.308 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:54.309 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-04 16:06:54.309 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 3F 03 70 05 C9 25 8F CC
2021-01-04 16:06:54.310 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 63: Sending REQUEST Message = 01 0A 00 13 3F 03 70 05 C9 25 8F CC
2021-01-04 16:06:54.311 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-04 16:06:54.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1199: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.312 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-04 16:06:54.313 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:54.314 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:54.315 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1199: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-04 16:06:54.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:54.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:54.318 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-04 16:06:54.320 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.321 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1199: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:54.322 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1199: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.323 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.323 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: sentData successfully placed on stack.
2021-01-04 16:06:54.324 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1199: Advanced to WAIT_REQUEST
2021-01-04 16:06:54.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1199: Transaction not completed
2021-01-04 16:06:54.325 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:54.326 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:54.613 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8F 00 00 1E 7A
2021-01-04 16:06:54.615 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 1E
2021-01-04 16:06:54.617 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 1E
2021-01-04 16:06:54.618 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1199: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.619 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:54.620 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1199: [WAIT_REQUEST] priority=Immediate, requiresResponse=true, callback: 143
2021-01-04 16:06:54.621 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1199: (Callback 143)
2021-01-04 16:06:54.622 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2021-01-04 16:06:54.623 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1199: callback 143
2021-01-04 16:06:54.624 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=143, payload=8F 00 00 1E
2021-01-04 16:06:54.625 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: SendData Request. CallBack ID = 143, Status = Transmission complete and ACK received(0)
2021-01-04 16:06:54.627 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:54.628 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1199: Advanced to WAIT_DATA
2021-01-04 16:06:54.629 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1199: Transaction not completed
2021-01-04 16:06:54.630 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:54.631 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2021-01-04 16:06:54.771 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 3F 06 70 06 C9 02 FB 01 89
2021-01-04 16:06:54.773 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 06 70 06 C9 02 FB 01
2021-01-04 16:06:54.775 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=63, callback=0, payload=00 3F 06 70 06 C9 02 FB 01
2021-01-04 16:06:54.776 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2021-01-04 16:06:54.777 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Application Command Request (ALIVE:DONE)
2021-01-04 16:06:54.778 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: resetResendCount initComplete=true isDead=false
2021-01-04 16:06:54.779 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: Incoming command class COMMAND_CLASS_CONFIGURATION, endpoint 0
2021-01-04 16:06:54.780 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 63: SECURITY not supported
2021-01-04 16:06:54.781 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 63: Received COMMAND_CLASS_CONFIGURATION V1 CONFIGURATIONCMD_REPORT
2021-01-04 16:06:54.781 [DEBUG] [class.ZWaveConfigurationCommandClass] - NODE 63: Node configuration report, parameter = 201, value = -1279, size = 2
2021-01-04 16:06:54.782 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveConfigurationParameterEvent
2021-01-04 16:06:54.783 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_CONFIGURATION, value=org.openhab.binding.zwave.internal.protocol.ZWaveConfigurationParameter@14d72e6
2021-01-04 16:06:54.784 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Update CONFIGURATION 201/2 to -1279
2021-01-04 16:06:54.785 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Commands processed 1.
2021-01-04 16:06:54.786 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b41724.
2021-01-04 16:06:54.787 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1b41724.
2021-01-04 16:06:54.788 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: notifyTransactionResponse TID:1199 DONE
2021-01-04 16:06:54.789 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 63: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2021-01-04 16:06:54.790 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2021-01-04 16:06:54.791 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2021-01-04 16:06:54.792 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2021-01-04 16:06:54.793 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2021-01-04 16:06:54.794 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 3F 02 84 08 25 90 E1
2021-01-04 16:06:54.795 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 63: Sending REQUEST Message = 01 09 00 13 3F 02 84 08 25 90 E1
2021-01-04 16:06:54.796 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2021-01-04 16:06:54.797 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2021-01-04 16:06:54.799 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:54.800 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1200: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2021-01-04 16:06:54.801 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2021-01-04 16:06:54.802 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1200: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2021-01-04 16:06:54.803 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2021-01-04 16:06:54.804 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2021-01-04 16:06:54.806 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.807 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.808 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1200: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2021-01-04 16:06:54.809 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2021-01-04 16:06:54.810 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1200: [WAIT_RESPONSE] priority=Immediate, requiresResponse=true, callback: 144
2021-01-04 16:06:54.811 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2021-01-04 16:06:54.812 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 63: sentData successfully placed on stack.
2021-01-04 16:06:54.813 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1200: Advanced to WAIT_REQUEST
2021-01-04 16:06:54.814 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 63: TID 1200: Transaction not completed
2021-01-04 16:06:54.815 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty