Zwave updating with CT100Plus

nhooverapg · March 10, 2018, 9:29pm

I’m new to OpenHAB, been reading a lot on the forums about installing and configuring my system- it’s been a ton of help, so thank you all.

I’m working with a CT100Plus thermostat, installed version 2.3.0.201803041016 of the Zwave binding. I’m communicating with the tstat, receiving updates, but can’t change the Heat Setpoint on the tstat through a sendCommand call in my rules. I have setup a proxy item design pattern (Design Pattern: Proxy Item) and if the setpoint is changed on the tstat, everything works. However, if the update comes from the sitemap Setpoint item, the setpoint never changes on the tstat.

Here is my Zwave log of the transaction:

10-Mar-2018 15:32:16.271 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 2: Command received zwave:rtc_ct100plus_00_000:controller:node2:thermostat_setpoint_heating --> 61
10-Mar-2018 15:32:16.271 [DEBUG] [nternal.converter.ZWaveThermostatSetpointConverter] - NODE 2: Thermostat command received for 61
10-Mar-2018 15:32:16.271 [DEBUG] [l.commandclass.ZWaveThermostatSetpointCommandClass] - NODE 2: Creating new message for command THERMOSTAT_SETPOINT_SET
10-Mar-2018 15:32:16.271 [DEBUG] [saction.ZWaveCommandClassTransactionPayloadBuilder] - At build null
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: Encapsulating message, endpoint 0
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_THERMOSTAT_SETPOINT is NOT required to be secured
10-Mar-2018 15:32:16.271 [DEBUG] [nternal.converter.ZWaveThermostatSetpointConverter] - NODE 2: Sending Message: org.openhab.binding.zwave.internal.protocol.transaction.ZWaveCommandClassTransactionPayload@1d08bc5
10-Mar-2018 15:32:16.271 [DEBUG] [l.commandclass.ZWaveThermostatSetpointCommandClass] - NODE 2: Creating new message for application command THERMOSTAT_SETPOINT_GET (Heating)
10-Mar-2018 15:32:16.271 [DEBUG] [saction.ZWaveCommandClassTransactionPayloadBuilder] - At build COMMAND_CLASS_THERMOSTAT_SETPOINT
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: Encapsulating message, endpoint 0
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: SECURITY not supported
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: Command Class COMMAND_CLASS_THERMOSTAT_SETPOINT is NOT required to be secured
10-Mar-2018 15:32:16.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
10-Mar-2018 15:32:16.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Added to queue - size 1
10-Mar-2018 15:32:16.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
10-Mar-2018 15:32:16.271 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: listening == true, frequentlyListening == false, awake == false
10-Mar-2018 15:32:16.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
10-Mar-2018 15:32:16.271 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
10-Mar-2018 15:32:16.271 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0C 00 13 02 05 43 01 01 09 3D 25 37 82 
10-Mar-2018 15:32:16.272 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0C 00 13 02 05 43 01 01 09 3D 25 37 82 
10-Mar-2018 15:32:16.274 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
10-Mar-2018 15:32:16.275 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: Transaction Start type SendData 
10-Mar-2018 15:32:16.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 578: [WAIT_RESPONSE] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: null
10-Mar-2018 15:32:16.275 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 0
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 2000ms
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 578: [WAIT_RESPONSE] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 2000ms
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Adding to device queue
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Added to queue - size 1
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
10-Mar-2018 15:32:16.276 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 1999ms
10-Mar-2018 15:32:16.276 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
10-Mar-2018 15:32:16.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.277 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 578: [WAIT_RESPONSE] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.278 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 1998ms
10-Mar-2018 15:32:16.282 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
10-Mar-2018 15:32:16.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.284 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 578: [WAIT_RESPONSE] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 578: [WAIT_RESPONSE] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.285 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.285 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
10-Mar-2018 15:32:16.285 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance ST: WAIT_RESPONSE
10-Mar-2018 15:32:16.285 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance WT: null {}
10-Mar-2018 15:32:16.285 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.285 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance TO: WAIT_REQUEST
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 578: Advanced to WAIT_REQUEST
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: TID 578: Transaction not completed
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.285 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:21 EST 2018 - 5000ms
10-Mar-2018 15:32:16.298 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 37 00 00 02 DE 
10-Mar-2018 15:32:16.299 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 578: [WAIT_REQUEST] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 578: [WAIT_REQUEST] requiresResponse=false callback: 55
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 578: (Callback 55)
10-Mar-2018 15:32:16.300 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 578: callback 55
10-Mar-2018 15:32:16.300 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 
10-Mar-2018 15:32:16.300 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 55, Status = Transmission complete and ACK received(0)
10-Mar-2018 15:32:16.300 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
10-Mar-2018 15:32:16.300 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: Transaction COMPLETED
10-Mar-2018 15:32:16.300 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance ST: DONE
10-Mar-2018 15:32:16.300 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance WT: null {}
10-Mar-2018 15:32:16.300 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=55, payload=37 00 00 02 
10-Mar-2018 15:32:16.300 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 578: TransactionAdvance TO: DONE
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Response processed after 25ms
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: TID 578: Transaction completed
10-Mar-2018 15:32:16.300 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:578 DONE
10-Mar-2018 15:32:16.301 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
10-Mar-2018 15:32:16.301 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
10-Mar-2018 15:32:16.301 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: listening == true, frequentlyListening == false, awake == false
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from sendQueue
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - getTransactionToSend 6
10-Mar-2018 15:32:16.301 [DEBUG] [nhab.binding.zwave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 02 03 43 02 01 25 38 BA 
10-Mar-2018 15:32:16.301 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - NODE 2: Sending REQUEST Message = 01 0A 00 13 02 03 43 02 01 25 38 BA 
10-Mar-2018 15:32:16.301 [DEBUG] [g.openhab.binding.zwave.handler.ZWaveSerialHandler] - Message SENT
10-Mar-2018 15:32:16.301 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: Transaction Start type SendData 
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 579: [WAIT_RESPONSE] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.301 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd class: COMMAND_CLASS_THERMOSTAT_SETPOINT
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: expected cmd: 3
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage Transactions outstanding: 1
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 2000ms
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage lastTransaction: TID 579: [WAIT_RESPONSE] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.302 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 2000ms
10-Mar-2018 15:32:16.302 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 579: [WAIT_RESPONSE] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg: ACK
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.303 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:18 EST 2018 - 1999ms
10-Mar-2018 15:32:16.309 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
10-Mar-2018 15:32:16.310 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 579: [WAIT_RESPONSE] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
10-Mar-2018 15:32:16.311 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 579: [WAIT_RESPONSE] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.311 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.311 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 2: sentData successfully placed on stack.
10-Mar-2018 15:32:16.311 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance ST: WAIT_RESPONSE
10-Mar-2018 15:32:16.312 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance WT: ApplicationCommandHandler {}
10-Mar-2018 15:32:16.312 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance RX: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01 
10-Mar-2018 15:32:16.312 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance TO: WAIT_REQUEST
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 579: Advanced to WAIT_REQUEST
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: TID 579: Transaction not completed
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction lastTransaction outstanding...
10-Mar-2018 15:32:16.312 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer
10-Mar-2018 15:32:16.313 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Start transaction timer to Sat Mar 10 15:32:21 EST 2018 - 4999ms
10-Mar-2018 15:32:16.326 [DEBUG] [wave.handler.ZWaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 38 00 00 02 D1 
10-Mar-2018 15:32:16.327 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=56, payload=38 00 00 02 
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - processReceiveMessage past lock Message: class=SendData[19], type=Request[0], dest=0, callback=56, payload=38 00 00 02 
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=56, payload=38 00 00 02 
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - lastTransaction TID 579: [WAIT_REQUEST] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Last transaction: TID 579: [WAIT_REQUEST] requiresResponse=false callback: 56
10-Mar-2018 15:32:16.328 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Checking TID 579: (Callback 56)
10-Mar-2018 15:32:16.328 [DEBUG] [ernal.protocol.serialmessage.ZWaveCommandProcessor] - Callback match!
10-Mar-2018 15:32:16.329 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Correlated to TID 579: callback 56
10-Mar-2018 15:32:16.330 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=56, payload=38 00 00 02 
10-Mar-2018 15:32:16.330 [DEBUG] [ternal.protocol.serialmessage.SendDataMessageClass] - NODE 2: SendData Request. CallBack ID = 56, Status = Transmission complete and ACK received(0)
10-Mar-2018 15:32:16.330 [DEBUG] [.openhab.binding.zwave.internal.protocol.ZWaveNode] - NODE 2: resetResendCount initComplete=true isDead=false
10-Mar-2018 15:32:16.330 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance ST: WAIT_REQUEST
10-Mar-2018 15:32:16.330 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance WT: ApplicationCommandHandler {}
10-Mar-2018 15:32:16.330 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance RX: Message: class=SendData[19], type=Request[0], dest=0, callback=56, payload=38 00 00 02 
10-Mar-2018 15:32:16.330 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance RQ: RREQ=false, RCLS=ApplicationCommandHandler
10-Mar-2018 15:32:16.331 [DEBUG] [b.binding.zwave.internal.protocol.ZWaveTransaction] - TID 579: TransactionAdvance TO: DONE
10-Mar-2018 15:32:16.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - TID 579: Advanced to DONE
10-Mar-2018 15:32:16.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: Response processed after 30ms
10-Mar-2018 15:32:16.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: TID 579: Transaction completed
10-Mar-2018 15:32:16.331 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - NODE 2: notifyTransactionResponse TID:579 DONE
10-Mar-2018 15:32:16.331 [DEBUG] [ab.binding.zwave.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
10-Mar-2018 15:32:16.331 [DEBUG] [rg.openhab.binding.zwave.handler.ZWaveThingHandler] - NODE 2: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
10-Mar-2018 15:32:16.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
10-Mar-2018 15:32:16.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start
10-Mar-2018 15:32:16.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction from controllerQueue
10-Mar-2018 15:32:16.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage nothing
10-Mar-2018 15:32:16.332 [DEBUG] [ng.zwave.internal.protocol.ZWaveTransactionManager] - STOP transaction timer

I can’t tell if this is an issue with the thermostat itself, or a configuration problem somewhere in OpenHAB. I’ve intentionally not included a bunch of existing configuration for ease of reading, but if something is needed I will post it. Thanks.

chris · March 19, 2018, 3:01pm

Sorry - I missed this one…

Can you provide the XML for the device from the userdata folder so I can check some of the configuration. The request to set the level looks ok at first glance at least (which is why I want to check the XML). Also, is there any response after this? The binding is requesting a report of the temperature so it would be good to see if there is a response.

nhooverapg · March 19, 2018, 8:57pm

XML for the radio: network_dc3a76e9__node_1.xml (1.7 KB)

XML for the tstat: network_dc3a76e9__node_2.xml (32.9 KB)

I double checked the operation- the zwave log was sitting idle, I changed the heat set point via BasicUI, log wen back idle. I didn’t see any response on the temperature. Here is that log: CT100 Plus Heat Setpoint.log.pdf (28.1 KB)

I tried the reverse, changing the set point on the tstat, here is that log: CT100 Plus Change Tstat.log.pdf (8.1 KB)

Interestingly, the first time I tried to change the set point on the device, it flashed my last change from the BasicUI (70), then flashed my manual change (66), then reverted to the original set point (65). I tried to reproduce this and couldn’t.

chris · March 20, 2018, 7:50am

I still don’t see any problem. You are setting the temperature to 70 degrees, and the thermostat is reporting 70 degrees after the change -:

In the above log, the value 46 is the temperature (70 degrees in hex) - the SET command sets 70, and the report says the thermostat is set to 70… It looks fine to me from this at least.

nhooverapg · March 20, 2018, 5:42pm

Yes, Chris, you are absolutely correct- the internal heat set point on the tstat is changing and keeping the set point correctly (as evidenced by the log and confirmed through REST). However, the front panel display is not updating to reflect the change, hence my initial confusion as to why it wasn’t working. Thanks for tracking down the Zwave side of things and explaining what’s going on. I’m looking into other causes of why the tstat isn’t updating the display properly.