Malfunctioning Qubino Flush RGB dimmer ZMNHWD3 device on openhab

goopilot · April 25, 2018, 3:43am

maybe @chris can advise. From what I see according to manual there are definitely missed configuration parameters.

chris · April 25, 2018, 7:16am

I’m not sure what the problem is - I’d need to see a log.

I’ll answer to a few comments though from above as I’ve not seen this before -:

Yes - the binding only uses a reduced set of colours - when setting RGB, it only sets the RGB. When setting whites, it uses the white channels. The other colours aren’t normally used, but I don’t see what this is a problem and doesn’t make it incomplete.

I don’t see any RGB numbers above 255, so I’m not sure what you’re looking at. The RGB conversion is unlikely to be the problem as it is using the code from ESH - ie the same for every other binding. If you have an example of high numbers being returned, then please provide it as the log I see here does not show RGB values above 255 that I can see.

Again - I must be missing something as I don’t see RGB values above 255. Please can someone provide this?

I can take a look at this, but only if someone can provide a debug log file to look at.

chris · April 26, 2018, 7:09am

Looking at the log, I think the issue is simply that the database is not configured properly. It is not sending the color commands at all due to this.

I’ll update the database and hopefully that will solve the issue.

goopilot · April 28, 2018, 3:53am

There are also many configuration parameters missing according to attached manual. I can add them.

fifo · April 28, 2018, 9:10am

Let me know when you would like me to test and if there is anything I can do to help. Not too familiar with all of this yet but I can get around a little.

chris · April 28, 2018, 9:40am

Thanks @goopilot - I’ve approved this and will hopefully do a binding update later today/tonight.

fifo · April 29, 2018, 8:51pm

@chris Did you get to make the changes to yesterdays build? If so, it’s still not working after updating to that build. Would I need to remove and add device again for changes to take?

chris · April 29, 2018, 9:39pm

I updated the build last night with the latest database - it was built this afternoon as I forgot to trigger the build yesterday.

chris · April 29, 2018, 9:42pm

Yes - in order to update the thing configuration you will need to delete and add the thing back again.

fifo · April 29, 2018, 11:34pm

@chris , seems to be working! From time to time it has a hiccup (might be the device itself) but overall it is working as expected. Works well with HabPanel and Sitemaps! Thanks for the help!

goopilot · May 1, 2018, 5:10am

Thanks @chris it’s working now. However as @fifo mentioned it behaves strange. I’m setting colour once but it’s bouncing values for some reason:

2018-04-30 23:45:43.259 [ome.event.ItemCommandEvent] - Item 'ZWaveNode12QubinoKitchenRGBWDimmer_ColorControl' received command 359,100,74
2018-04-30 23:45:43.263 [vent.ItemStateChangedEvent] - ZWaveNode12QubinoKitchenRGBWDimmer_ColorControl changed from 0,0,0 to 359,100,74
2018-04-30 23:45:44.177 [vent.ItemStateChangedEvent] - ZWaveNode12QubinoKitchenRGBWDimmer_ColorControl changed from 359,100,74 to 0,100,20

From my value 359,100,74 it’s changing to 0,100,20. And in openhab.log it is completely different.

2018-04-30 23:45:43.603 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report BLUE 0
2018-04-30 23:45:43.803 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report GREEN 0
2018-04-30 23:45:43.973 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report WARM_WHITE 0
2018-04-30 23:45:44.173 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report RED 51
2018-04-30 23:45:44.174 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report finished {BLUE=0, GREEN=0, WARM_WHITE=0, RED=51}

chris · May 1, 2018, 10:19am

Please provide a debug log and I’ll take a look at what’s happening.

goopilot · May 1, 2018, 1:11pm

2018-05-01 08:03:59.654 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:03:59.654 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 16: Node advancer - PING: Transaction complete (SendData:Request) success(false)
2018-05-01 08:04:16.127 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Command received zwave:device:8307e216:node17:color_color --> 359,100,74
2018-05-01 08:04:16.139 [DEBUG] [ternal.converter.ZWaveColorConverter] - NODE 17: Converted command '359,100,74' to value 74 0 1 for channel = zwave:device:8307e216:node17:color_color, endpoint = 0.
2018-05-01 08:04:16.149 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Creating new message for application command SWITCH_COLOR_GET 3
2018-05-01 08:04:16.150 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Creating new message for application command SWITCH_COLOR_GET 0
2018-05-01 08:04:16.150 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Creating new message for application command SWITCH_COLOR_GET 4
2018-05-01 08:04:16.151 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Creating new message for application command SWITCH_COLOR_GET 2
2018-05-01 08:04:16.157 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-05-01 08:04:16.158 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 15 00 13 11 0E 33 05 05 02 4A 03 00 04 01 00 00 01 00 FF 25 86 C6 
2018-05-01 08:04:16.159 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 17: Sending REQUEST Message = 01 15 00 13 11 0E 33 05 05 02 4A 03 00 04 01 00 00 01 00 FF 25 86 C6 
2018-05-01 08:04:16.160 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 0. Queue={}
2018-05-01 08:04:16.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 1. Queue={}
2018-05-01 08:04:16.161 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 2. Queue={}
2018-05-01 08:04:16.162 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 3. Queue={}
2018-05-01 08:04:16.167 [DEBUG] [ve.internal.protocol.ZWaveController] - Message queued. Queue length = 4. Queue={}
2018-05-01 08:04:16.168 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.170 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.171 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-01 08:04:16.171 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.172 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-01 08:04:16.172 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: Sent Data successfully placed on stack.
2018-05-01 08:04:16.191 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 86 00 00 03 6E 
2018-05-01 08:04:16.192 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.193 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 86 00 00 03 00 00 60 
2018-05-01 08:04:16.193 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 86 00 00 03 00 00 60 
2018-05-01 08:04:16.194 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=86 00 00 03 
2018-05-01 08:04:16.194 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: SendData Request. CallBack ID = 134, Status = Transmission complete and ACK received(0)
2018-05-01 08:04:16.195 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.199 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.202 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Set, dest=17, callback=134, payload=11 0E 33 05 05 02 4A 03 00 04 01 00 00 01 00 FF 
2018-05-01 08:04:16.203 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=86 00 00 03 
2018-05-01 08:04:16.204 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=134, expected=SendData, cancelled=false        transaction complete!
2018-05-01 08:04:16.208 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.214 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.216 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 17: Response processed after 56ms/3560ms.
2018-05-01 08:04:16.217 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 3
2018-05-01 08:04:16.218 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 11 03 33 03 03 25 87 65 
2018-05-01 08:04:16.218 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 17: Sending REQUEST Message = 01 0A 00 13 11 03 33 03 03 25 87 65 
2018-05-01 08:04:16.227 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.229 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.230 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-01 08:04:16.231 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.233 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-01 08:04:16.234 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: Sent Data successfully placed on stack.
2018-05-01 08:04:16.303 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 87 00 00 08 64 
2018-05-01 08:04:16.304 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.305 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 87 00 00 08 00 00 6A 
2018-05-01 08:04:16.305 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 87 00 00 08 00 00 6A 
2018-05-01 08:04:16.306 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=87 00 00 08 
2018-05-01 08:04:16.306 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: SendData Request. CallBack ID = 135, Status = Transmission complete and ACK received(0)
2018-05-01 08:04:16.307 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.308 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.309 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=135, payload=11 03 33 03 03 
2018-05-01 08:04:16.309 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=87 00 00 08 
2018-05-01 08:04:16.310 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=135, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-01 08:04:16.326 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 11 04 33 04 03 00 D0 
2018-05-01 08:04:16.327 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.327 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 11 04 33 04 03 00 D0 
2018-05-01 08:04:16.328 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 11 04 33 04 03 00 D0 
2018-05-01 08:04:16.329 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 03 00 
2018-05-01 08:04:16.330 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2018-05-01 08:04:16.330 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.331 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.331 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class COLOR
2018-05-01 08:04:16.332 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Received COMMAND_CLASS_SWITCH_COLOR V2
2018-05-01 08:04:16.333 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Process Color SWITCH_COLOR_REPORT
2018-05-01 08:04:16.334 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report GREEN 0
2018-05-01 08:04:16.334 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=135, payload=11 03 33 03 03 
2018-05-01 08:04:16.335 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 03 00 
2018-05-01 08:04:16.336 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=135, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-01 08:04:16.336 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.337 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.337 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 17: Response processed after 117ms/3560ms.
2018-05-01 08:04:16.338 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 2
2018-05-01 08:04:16.339 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 11 03 33 03 00 25 88 69 
2018-05-01 08:04:16.339 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 17: Sending REQUEST Message = 01 0A 00 13 11 03 33 03 00 25 88 69 
2018-05-01 08:04:16.348 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.351 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.352 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-01 08:04:16.352 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.353 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-01 08:04:16.353 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: Sent Data successfully placed on stack.
2018-05-01 08:04:16.368 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 88 00 00 02 61 
2018-05-01 08:04:16.369 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.371 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 88 00 00 02 00 00 6F 
2018-05-01 08:04:16.373 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 88 00 00 02 00 00 6F 
2018-05-01 08:04:16.374 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=88 00 00 02 
2018-05-01 08:04:16.374 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: SendData Request. CallBack ID = 136, Status = Transmission complete and ACK received(0)
2018-05-01 08:04:16.375 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.376 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.376 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=136, payload=11 03 33 03 00 
2018-05-01 08:04:16.377 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=88 00 00 02 
2018-05-01 08:04:16.378 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=136, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-01 08:04:16.382 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 11 04 33 04 00 00 D3 
2018-05-01 08:04:16.383 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.384 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 11 04 33 04 00 00 D3 
2018-05-01 08:04:16.385 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 11 04 33 04 00 00 D3 
2018-05-01 08:04:16.393 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 00 00 
2018-05-01 08:04:16.395 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2018-05-01 08:04:16.396 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.398 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.400 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class COLOR
2018-05-01 08:04:16.400 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Received COMMAND_CLASS_SWITCH_COLOR V2
2018-05-01 08:04:16.402 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Process Color SWITCH_COLOR_REPORT
2018-05-01 08:04:16.403 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report WARM_WHITE 0
2018-05-01 08:04:16.403 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=136, payload=11 03 33 03 00 
2018-05-01 08:04:16.404 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 00 00 
2018-05-01 08:04:16.405 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=136, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-01 08:04:16.405 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.406 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.406 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 17: Response processed after 66ms/3560ms.
2018-05-01 08:04:16.407 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 1
2018-05-01 08:04:16.408 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 11 03 33 03 04 25 89 6C 
2018-05-01 08:04:16.408 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 17: Sending REQUEST Message = 01 0A 00 13 11 03 33 03 04 25 89 6C 
2018-05-01 08:04:16.417 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.450 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.451 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-01 08:04:16.454 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 89 00 00 02 60 
2018-05-01 08:04:16.457 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.459 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 11 04 33 04 04 00 D7 
2018-05-01 08:04:16.459 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-01 08:04:16.460 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: Sent Data successfully placed on stack.
2018-05-01 08:04:16.461 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=1
2018-05-01 08:04:16.462 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 89 00 00 02 00 00 6E 
2018-05-01 08:04:16.463 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 89 00 00 02 00 00 6E 
2018-05-01 08:04:16.464 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=89 00 00 02 
2018-05-01 08:04:16.467 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: SendData Request. CallBack ID = 137, Status = Transmission complete and ACK received(0)
2018-05-01 08:04:16.468 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.469 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.469 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=137, payload=11 03 33 03 04 
2018-05-01 08:04:16.470 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=89 00 00 02 
2018-05-01 08:04:16.470 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=137, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-01 08:04:16.471 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.471 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 11 04 33 04 04 00 D7 
2018-05-01 08:04:16.472 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 11 04 33 04 04 00 D7 
2018-05-01 08:04:16.473 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 04 00 
2018-05-01 08:04:16.476 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2018-05-01 08:04:16.477 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.478 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.479 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class COLOR
2018-05-01 08:04:16.480 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Received COMMAND_CLASS_SWITCH_COLOR V2
2018-05-01 08:04:16.480 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Process Color SWITCH_COLOR_REPORT
2018-05-01 08:04:16.481 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report BLUE 0
2018-05-01 08:04:16.481 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=137, payload=11 03 33 03 04 
2018-05-01 08:04:16.482 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 04 00 
2018-05-01 08:04:16.485 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=137, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-01 08:04:16.486 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.499 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.499 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 17: Response processed after 90ms/3560ms.
2018-05-01 08:04:16.501 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - Took message from queue for sending. Queue length = 0
2018-05-01 08:04:16.502 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 11 03 33 03 02 25 8A 69 
2018-05-01 08:04:16.503 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 17: Sending REQUEST Message = 01 0A 00 13 11 03 33 03 02 25 8A 69 
2018-05-01 08:04:16.512 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.516 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.517 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 04 01 13 01 E8 
2018-05-01 08:04:16.519 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 04 01 13 01 E8 
2018-05-01 08:04:16.520 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Response[0x01], priority=High, dest=255, callback=0, payload=01 
2018-05-01 08:04:16.520 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: Sent Data successfully placed on stack.
2018-05-01 08:04:16.533 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 07 00 13 8A 00 00 03 62 
2018-05-01 08:04:16.537 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.540 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 09 00 13 8A 00 00 03 00 00 6C 
2018-05-01 08:04:16.541 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 09 00 13 8A 00 00 03 00 00 6C 
2018-05-01 08:04:16.542 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=8A 00 00 03 
2018-05-01 08:04:16.542 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 17: SendData Request. CallBack ID = 138, Status = Transmission complete and ACK received(0)
2018-05-01 08:04:16.543 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.544 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.544 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=138, payload=11 03 33 03 02 
2018-05-01 08:04:16.545 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=SendData[0x13], type=Request[0x00], priority=High, dest=255, callback=0, payload=8A 00 00 03 
2018-05-01 08:04:16.545 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=SendData, callback id=138, expected=ApplicationCommandHandler, cancelled=false      MISMATCH
2018-05-01 08:04:16.547 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0A 00 04 00 11 04 33 04 02 12 C3 
2018-05-01 08:04:16.549 [DEBUG] [ve.internal.protocol.ZWaveController] - Receive queue TAKE: Length=0
2018-05-01 08:04:16.552 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 04 00 11 04 33 04 02 12 C3 
2018-05-01 08:04:16.553 [DEBUG] [ve.internal.protocol.ZWaveController] - Process Message = 01 0A 00 04 00 11 04 33 04 02 12 C3 
2018-05-01 08:04:16.554 [DEBUG] [ve.internal.protocol.ZWaveController] - Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 02 12 
2018-05-01 08:04:16.556 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Application Command Request (ALIVE:DONE)
2018-05-01 08:04:16.559 [DEBUG] [alization.ZWaveNodeInitStageAdvancer] - NODE 17: Starting initialisation from DONE
2018-05-01 08:04:16.561 [DEBUG] [ve.internal.protocol.ZWaveController] - Event Listener org.openhab.binding.zwave.internal.protocol.initialization.ZWaveNodeInitStageAdvancer@aa1c8d8 already registered
2018-05-01 08:04:16.561 [DEBUG] [ssage.ApplicationCommandMessageClass] - NODE 17: Incoming command class COLOR
2018-05-01 08:04:16.563 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Received COMMAND_CLASS_SWITCH_COLOR V2
2018-05-01 08:04:16.564 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 17: Process Color SWITCH_COLOR_REPORT
2018-05-01 08:04:16.565 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report RED 18
2018-05-01 08:04:16.566 [INFO ] [.commandclass.ZWaveColorCommandClass] - NODE 17: Color report finished {GREEN=0, WARM_WHITE=0, BLUE=0, RED=18}
2018-05-01 08:04:16.567 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveColorValueEvent
2018-05-01 08:04:16.568 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveColorValueEvent
2018-05-01 08:04:16.568 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got a value event from Z-Wave network, endpoint = 0, command class = COLOR, value = 0
2018-05-01 08:04:16.569 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:8307e216:node17:color_color to 0,100,7 [HSBType]
2018-05-01 08:04:16.574 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Updating channel state zwave:device:8307e216:node17:color_temperature to 0 [PercentType]
2018-05-01 08:04:16.584 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Sent Message: class=SendData[0x13], type=Request[0x00], priority=Get, dest=17, callback=138, payload=11 03 33 03 02 
2018-05-01 08:04:16.593 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: Recv Message: class=ApplicationCommandHandler[0x04], type=Request[0x00], priority=High, dest=255, callback=0, payload=00 11 04 33 04 02 12 
2018-05-01 08:04:16.598 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Checking transaction complete: class=ApplicationCommandHandler, callback id=138, expected=ApplicationCommandHandler, cancelled=false        transaction complete!
2018-05-01 08:04:16.599 [DEBUG] [ve.internal.protocol.ZWaveController] - Notifying event listeners: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.600 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 17: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2018-05-01 08:04:16.602 [DEBUG] [ocol.ZWaveController$ZWaveSendThread] - NODE 17: Response processed after 97ms/3560ms.

goopilot · May 1, 2018, 1:11pm

Node 17 is my device

ufoDziner · November 15, 2018, 5:20am

@goopilot Did you ever get this resolved? I’m looking at getting one of these controllers, but don’t want something that will make uncommanded changes. Thanks

goopilot · November 15, 2018, 3:40pm

I think my issue was that I was sending HSL format instead of RGB. Need to test it with RGB.

ufoDziner · November 16, 2018, 4:35am

Gotcha. Please let me know what you find out. I’d really like to get a few of these setup. Thanks!

ufoDziner · November 19, 2018, 2:05am

I recently purchased one of these, and the color selector isn’t matching up. This is the way the item color selection is listed in my items file:

Color    mBthSnkLgtRGBWClr   "Sink Color Lights Color"   <colorlight>   (gMaster,gMasterBath)    {channel="zwave:device:01FFFFFF-FFFF-FFFF-FFFF-160118150928:node36:color_color"}

Is there something obvious that I’m doing wrong? Thanks

ufoDziner · November 19, 2018, 4:00am

Actually, it looks as if the color wheel works, just that the colors are incorrectly assigned. Almost like the displayed color wheel is off by 90 degrees. Any ideas are welcome.

ufoDziner · November 20, 2018, 11:58pm

So, what I found out, and this might pertain to you as well @goopilot, the wires soldered to the RGBW strip were IN THE WRONG ORDER FROM THE FACTORY! I couldn’t believe it. I cut the shrink tube off to change the connector to something more standard, and there it was. Now it all works. I need to work out a better control scenario, but at least it’s functioning correctly.