Hi Chris. Thanks for your response.
With logging set with “log:set DEBUG org.openhab.binding.zwave”, I capture a log portion where the command Backyard_Gate_Color_Indicator.sendCommand(“360,100,100”) was issued.
Looks like that starts around timestamp 2018-06-20 06:28:17.048
2018-06-20 06:28:16.977 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 5: Sending REQUEST Message = 01 0A 00 13 05 03 25 01 FF 25 2C 32
2018-06-20 06:28:16.986 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-06-20 06:28:16.988 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:17.002 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2C 00 00 02 C5
2018-06-20 06:28:16.991 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-06-20 06:28:17.008 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-06-20 06:28:17.010 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-06-20 06:28:17.011 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: Sent Data successfully placed on stack.
2018-06-20 06:28:17.012 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:17.013 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2C 00 00 02 00 00 CB
2018-06-20 06:28:17.013 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2C 00 00 02 00 00 CB
2018-06-20 06:28:17.014 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2C 00 00 02
2018-06-20 06:28:17.015 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 5: SendData Request. CallBack ID = 44, Status = Transmission complete and ACK received(0)
2018-06-20 06:28:17.015 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 5: Starting initialisation from DONE
2018-06-20 06:28:17.016 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@36444c4b already registered
2018-06-20 06:28:17.017 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=5, callback=44, payload=05 03 25 01 FF
2018-06-20 06:28:17.018 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2C 00 00 02
2018-06-20 06:28:17.019 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=44, expected=SendData, cancelled=false transaction complete!
2018-06-20 06:28:17.019 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-06-20 06:28:17.020 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 5: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-06-20 06:28:17.021 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 5: Response processed after 43ms/4146ms.
2018-06-20 06:28:17.048 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Command received zwave:device:07c3b712:node12:color_color --> 360,100,100
2018-06-20 06:28:17.052 [DEBUG] [ternal.converter.ZWaveColorConverter] - NODE 12: Converted command '360,100,100' to value 100 0 0 for channel = zwave:device:07c3b712:node12:color_color, endpoint = 0.
2018-06-20 06:28:17.054 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE Node 12. Manufacturer 001E, Type 0004, Id 0001: Color refresh is already in progress
2018-06-20 06:28:17.055 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-06-20 06:28:17.056 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-06-20 06:28:17.056 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 14 00 13 0C 0D 33 05 05 02 64 03 00 04 00 00 00 01 00 25 2D A2
2018-06-20 06:28:17.057 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 12: Sending REQUEST Message = 01 14 00 13 0C 0D 33 05 05 02 64 03 00 04 00 00 00 01 00 25 2D A2
2018-06-20 06:28:17.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-06-20 06:28:17.068 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:17.069 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-06-20 06:28:17.070 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-06-20 06:28:17.071 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-06-20 06:28:17.075 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: Sent Data successfully placed on stack.
2018-06-20 06:28:17.084 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2D 00 00 02 C4
2018-06-20 06:28:17.085 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:17.086 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2D 00 00 02 00 00 CA
2018-06-20 06:28:17.087 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2D 00 00 02 00 00 CA
2018-06-20 06:28:17.087 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2D 00 00 02
2018-06-20 06:28:17.088 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 12: SendData Request. CallBack ID = 45, Status = Transmission complete and ACK received(0)
2018-06-20 06:28:17.089 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 12: Starting initialisation from DONE
2018-06-20 06:28:17.091 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@1d48b06a already registered
2018-06-20 06:28:17.094 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=12, callback=45, payload=0C 0D 33 05 05 02 64 03 00 04 00 00 00 01 00
2018-06-20 06:28:17.098 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=2D 00 00 02
2018-06-20 06:28:17.100 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=45, expected=SendData, cancelled=false transaction complete!
2018-06-20 06:28:17.101 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-06-20 06:28:17.102 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 12: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-06-20 06:28:17.103 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 12: Response processed after 45ms/4146ms.
2018-06-20 06:28:24.162 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling...
2018-06-20 06:28:24.164 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:07c3b712:node7:switch_binary
2018-06-20 06:28:24.166 [DEBUG] [converter.ZWaveBinarySwitchConverter] - NODE 7: Generating poll message for SWITCH_BINARY, endpoint 0
2018-06-20 06:28:24.168 [DEBUG] [dclass.ZWaveBinarySwitchCommandClass] - NODE 7: Creating new message for application command SWITCH_BINARY_GET
2018-06-20 06:28:24.170 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:07c3b712:node7:sensor_binary
2018-06-20 06:28:24.173 [DEBUG] [converter.ZWaveBinarySensorConverter] - NODE 7: Generating poll message for SENSOR_BINARY, endpoint 0
2018-06-20 06:28:24.175 [DEBUG] [dclass.ZWaveBinarySensorCommandClass] - NODE 7: Creating new message for application command SENSOR_BINARY_GET
2018-06-20 06:28:24.177 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:07c3b712:node7:sensor_general
2018-06-20 06:28:24.178 [DEBUG] [erter.ZWaveMultiLevelSensorConverter] - NODE 7: Generating poll message for SENSOR_MULTILEVEL, endpoint 0
2018-06-20 06:28:24.180 [DEBUG] [ss.ZWaveMultiLevelSensorCommandClass] - NODE 7: Creating new message for command SENSOR_MULTI_LEVEL_GET
2018-06-20 06:28:24.182 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 7: Polling zwave:device:07c3b712:node7:alarm_general
2018-06-20 06:28:24.183 [DEBUG] [ternal.converter.ZWaveAlarmConverter] - NODE 7: Generating poll message for ALARM, endpoint 0, alarm null, event null
2018-06-20 06:28:24.184 [DEBUG] [.commandclass.ZWaveAlarmCommandClass] - NODE 7: Creating new message for application command NOTIFICATION_GET V1
2018-06-20 06:28:24.185 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-06-20 06:28:24.185 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-06-20 06:28:24.186 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-06-20 06:28:24.186 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 07 02 25 02 25 2E CC
2018-06-20 06:28:24.187 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2018-06-20 06:28:24.188 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 7: Sending REQUEST Message = 01 09 00 13 07 02 25 02 25 2E CC
2018-06-20 06:28:24.188 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 3. Queue={}
2018-06-20 06:28:24.197 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2018-06-20 06:28:24.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:24.199 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8
2018-06-20 06:28:24.199 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8
2018-06-20 06:28:24.201 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01
2018-06-20 06:28:24.202 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 7: Sent Data successfully placed on stack.
2018-06-20 06:28:24.214 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 2E 00 00 02 C7
2018-06-20 06:28:24.215 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-06-20 06:28:24.216 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 2E 00 00 02 00 00 C9
2018-06-20 06:28:24.216 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 2E 00 00 02 00 00 C9