RGB light bulb color control

alguevel · November 24, 2020, 11:22pm

Hello,

New with openHab, I started my setup and it’s going quite well except I’m stilll not able to control the color my RGBW light bulb (zipato)
setup informations:

Hardware: RPI 4
OS: Raspbian /openhabian image
Java Runtime Environment: which java platform is used and what version
openHAB version: 2.5.10 (installed with OpenHabian) / Zulu 8.50)
dongle z stick with zipato RGBW light bulb 1

For the moment :

I can turn it on/off
I can control the white light from warm to cold
I have been able to control the color on Home Seer,(I took a trial just for this test) so I know that there is nothing wrong on the device.
The “color” control in OpenHAB does nothing for the moment
My configuration looks like this : (limited to 1 screenshot…)

Only logs I’m seeing (I did not find where to turn it on debug mode) :
2020-11-24 23:44:34.719 [ome.event.ItemCommandEvent] - Item ‘zwave_device_04ca0044_node42_color_color’ received command 272,0,0
2020-11-24 23:44:34.743 [nt.ItemStatePredictedEvent] - zwave_device_04ca0044_node42_color_color predicted to become 272,0,0
2020-11-24 23:44:34.757 [vent.ItemStateChangedEvent] - zwave_device_04ca0044_node42_color_color changed from 310,0,0 to 272,0,0

Nothing really interesting except that apparently the color value is correctly updated.
Could someone provide some help ? some suggestion to debug this ?
If it means that I need to do some adaptation/code myself in order to send the correct commands I’m also interested for some advice on this. Thank you in advance !

Bruce_Osborne · November 25, 2020, 12:11am

The first place I would look is the zwave binding documentation where they say to capture DEBUG logs. Your system knows what is happening. We just need it to tell you.

alguevel · November 25, 2020, 9:50pm

Thanks for answering. I set my zwave logs to debug, here is what I get when I try to change color :
2 0-11-25 22:36:03.736 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE) 2020-11-25 22:36:03.737 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false 2020-11-25 22:36:03.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0 2020-11-25 22:36:03.738 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported 2020-11-25 22:36:03.739 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT 2020-11-25 22:36:03.740 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report GREEN 2 2020-11-25 22:36:03.741 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report finished {BLUE=2, WARM_WHITE=2, RED=2, COLD_WHITE=255, GREEN=2} 2020-11-25 22:36:03.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveColorValueEvent 202011-25 22:36:03.742 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_COLOR, value=0 2020-11-25 22:36:03.743 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:color_color to 0,0,0 [HSBType] 2020-11-25 22:36:03.745 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:color_temperature to 0 [PercentType]

RGB does not change. But I’m able to change warm/cold white.

So there are 5 parameters : Red,Blue,Green, Warm White , Cold White
I see on home seer, when the color was OK, that I need to put warm and cold white to 0 in order to control the color.
Any suggestion ? How can I can control this 4parameters independantly ?

Thanks

Bruce_Osborne · November 25, 2020, 10:07pm

Please use code fences.

Thoes logs look filtered for node 43. The developer and experts here can only use unfiltered logs for troubleshooting.

alguevel · November 25, 2020, 10:28pm

Sorry ! Here is another extract of logs with 2 trials of setting the color using the control in the GUI:
Here is the full log file color_rgbw.log (127.1 KB)

LOGS


Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:31.922 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:31.923 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1304: Advanced to WAIT_DATA
2020-11-25 23:09:31.924 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1304: Transaction not completed
2020-11-25 23:09:31.925 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 03 26 03 45 C9 00
2020-11-25 23:09:31.926 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:31.928 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:31.928 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:31.930 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_MULTILEVEL, endpoint 0
2020-11-25 23:09:31.931 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:31.932 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_MULTILEVEL V1 SWITCH_MULTILEVEL_REPORT
2020-11-25 23:09:31.933 [DEBUG] [ss.ZWaveMultiLevelSwitchCommandClass] - NODE 43: Switch Multi Level report, value = 69
2020-11-25 23:09:31.934 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveCommandClassValueEvent
2020-11-25 23:09:31.935 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_MULTILEVEL, value=69
2020-11-25 23:09:31.937 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:switch_dimmer to 69 [PercentType]
2020-11-25 23:09:31.940 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:color_color to 69 [PercentType]
2020-11-25 23:09:31.941 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.
2020-11-25 23:09:31.943 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@17c24a4.
2020-11-25 23:09:31.944 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@17c24a4.
2020-11-25 23:09:31.945 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1304 DONE
2020-11-25 23:09:31.947 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-11-25 23:09:31.948 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-11-25 23:09:31.949 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-11-25 23:09:31.950 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:31.951 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-25 23:09:31.953 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 2B 03 33 03 04 25 81 5E
2020-11-25 23:09:31.954 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 43: Sending REQUEST Message = 01 0A 00 13 2B 03 33 03 04 25 81 5E
2020-11-25 23:09:31.955 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-25 23:09:31.957 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-25 23:09:31.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1310: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.958 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:31.960 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:31.961 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1310: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.962 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-25 23:09:31.963 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:31.963 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-11-25 23:09:31.965 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:31.965 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:31.966 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:31.967 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1310: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.968 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:31.969 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1310: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.971 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:31.972 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: sentData successfully placed on stack.
2020-11-25 23:09:31.973 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1310: Advanced to WAIT_REQUEST
2020-11-25 23:09:31.974 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1310: Transaction not completed
2020-11-25 23:09:31.975 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:31.976 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:31.983 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 81 00 00 02 00 CA 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 BC
2020-11-25 23:09:31.987 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=129, payload=81 00 00 02 00 CA 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:31.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=129, payload=81 00 00 02 00 CA 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:31.989 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1310: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.991 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:31.992 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1310: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 129
2020-11-25 23:09:31.993 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1310: (Callback 129)
2020-11-25 23:09:31.994 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-25 23:09:31.995 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1310: callback 129
2020-11-25 23:09:31.996 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 2B 04 33 04 04 02 C9 00 20
2020-11-25 23:09:31.996 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=129, payload=81 00 00 02 00 CA 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:31.998 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: SendData Request. CallBack ID = 129, Status = Transmission complete and ACK received(0)
2020-11-25 23:09:31.998 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 04 02 C9 00
2020-11-25 23:09:31.999 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.001 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1310: Advanced to WAIT_DATA
2020-11-25 23:09:32.002 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1310: Transaction not completed
2020-11-25 23:09:32.003 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 04 02 C9 00
2020-11-25 23:09:32.004 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:32.005 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:32.006 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.008 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0
2020-11-25 23:09:32.008 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:32.010 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT
2020-11-25 23:09:32.011 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report BLUE 2
2020-11-25 23:09:32.012 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.
2020-11-25 23:09:32.013 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f541d1.
2020-11-25 23:09:32.014 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@f541d1.
2020-11-25 23:09:32.015 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1310 DONE
2020-11-25 23:09:32.017 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-11-25 23:09:32.018 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-11-25 23:09:32.019 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-11-25 23:09:32.020 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.021 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-25 23:09:32.022 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 2B 03 33 03 00 25 82 59
2020-11-25 23:09:32.024 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 43: Sending REQUEST Message = 01 0A 00 13 2B 03 33 03 00 25 82 59
2020-11-25 23:09:32.025 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-25 23:09:32.027 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1311: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.027 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-25 23:09:32.028 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.030 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.031 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1311: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.032 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-25 23:09:32.033 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.033 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-11-25 23:09:32.034 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.035 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.036 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.037 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1311: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.039 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1311: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.041 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.042 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: sentData successfully placed on stack.
2020-11-25 23:09:32.043 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1311: Advanced to WAIT_REQUEST
2020-11-25 23:09:32.044 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1311: Transaction not completed
2020-11-25 23:09:32.045 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.046 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.054 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 82 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 BC
2020-11-25 23:09:32.057 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=130, payload=82 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.059 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=130, payload=82 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.060 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1311: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.061 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.062 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1311: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 130
2020-11-25 23:09:32.063 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1311: (Callback 130)
2020-11-25 23:09:32.064 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-25 23:09:32.066 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1311: callback 130
2020-11-25 23:09:32.067 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 2B 04 33 04 00 02 C9 00 24
2020-11-25 23:09:32.068 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=130, payload=82 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.069 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 00 02 C9 00
2020-11-25 23:09:32.070 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: SendData Request. CallBack ID = 130, Status = Transmission complete and ACK received(0)
2020-11-25 23:09:32.071 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.072 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1311: Advanced to WAIT_DATA
2020-11-25 23:09:32.073 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1311: Transaction not completed
2020-11-25 23:09:32.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 00 02 C9 00
2020-11-25 23:09:32.075 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:32.077 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:32.077 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.079 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0
2020-11-25 23:09:32.080 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:32.081 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT
2020-11-25 23:09:32.082 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report WARM_WHITE 2
2020-11-25 23:09:32.083 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.
2020-11-25 23:09:32.084 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14534f3.
2020-11-25 23:09:32.085 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@14534f3.
2020-11-25 23:09:32.086 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1311 DONE
2020-11-25 23:09:32.088 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-11-25 23:09:32.089 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-11-25 23:09:32.090 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-11-25 23:09:32.091 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.092 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-25 23:09:32.094 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 2B 03 33 03 02 25 83 5A
2020-11-25 23:09:32.095 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 43: Sending REQUEST Message = 01 0A 00 13 2B 03 33 03 02 25 83 5A
2020-11-25 23:09:32.097 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-25 23:09:32.098 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-25 23:09:32.098 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1312: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.100 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.101 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1312: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.102 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-25 23:09:32.103 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.104 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.105 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-11-25 23:09:32.106 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.108 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1312: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.109 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.110 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1312: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.111 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.112 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: sentData successfully placed on stack.
2020-11-25 23:09:32.113 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1312: Advanced to WAIT_REQUEST
2020-11-25 23:09:32.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1312: Transaction not completed
2020-11-25 23:09:32.114 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.115 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.124 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 83 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 BD
2020-11-25 23:09:32.127 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=131, payload=83 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.129 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=131, payload=83 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.130 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1312: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.131 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1312: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 131
2020-11-25 23:09:32.132 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1312: (Callback 131)
2020-11-25 23:09:32.133 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-25 23:09:32.134 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1312: callback 131
2020-11-25 23:09:32.135 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=131, payload=83 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.136 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: SendData Request. CallBack ID = 131, Status = Transmission complete and ACK received(0)
2020-11-25 23:09:32.137 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 2B 04 33 04 02 02 C9 00 26
2020-11-25 23:09:32.137 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.138 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1312: Advanced to WAIT_DATA
2020-11-25 23:09:32.139 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1312: Transaction not completed
2020-11-25 23:09:32.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 02 02 C9 00
2020-11-25 23:09:32.140 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.141 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.142 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 02 02 C9 00
2020-11-25 23:09:32.143 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:32.144 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:32.145 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0
2020-11-25 23:09:32.146 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:32.147 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT
2020-11-25 23:09:32.148 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report RED 2
2020-11-25 23:09:32.149 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.
2020-11-25 23:09:32.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10c6a22.
2020-11-25 23:09:32.150 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@10c6a22.
2020-11-25 23:09:32.151 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1312 DONE
2020-11-25 23:09:32.152 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-11-25 23:09:32.153 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-11-25 23:09:32.154 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-11-25 23:09:32.155 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.156 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-25 23:09:32.157 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 2B 03 33 03 01 25 84 5E
2020-11-25 23:09:32.158 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 43: Sending REQUEST Message = 01 0A 00 13 2B 03 33 03 01 25 84 5E
2020-11-25 23:09:32.159 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-25 23:09:32.160 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1313: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.161 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-25 23:09:32.162 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.163 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.164 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1313: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.165 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-25 23:09:32.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.166 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.167 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-11-25 23:09:32.169 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.170 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.171 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1313: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.172 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.173 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1313: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.174 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.175 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: sentData successfully placed on stack.
2020-11-25 23:09:32.175 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1313: Advanced to WAIT_REQUEST
2020-11-25 23:09:32.176 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1313: Transaction not completed
2020-11-25 23:09:32.177 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.178 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.187 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 84 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 BA
2020-11-25 23:09:32.190 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=132, payload=84 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.192 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=132, payload=84 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1313: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.193 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.194 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1313: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 132
2020-11-25 23:09:32.195 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1313: (Callback 132)
2020-11-25 23:09:32.196 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-25 23:09:32.197 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1313: callback 132
2020-11-25 23:09:32.198 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=132, payload=84 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.199 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: SendData Request. CallBack ID = 132, Status = Transmission complete and ACK received(0)
2020-11-25 23:09:32.200 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 2B 04 33 04 01 F9 C9 00 DE
2020-11-25 23:09:32.200 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.201 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1313: Advanced to WAIT_DATA
2020-11-25 23:09:32.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1313: Transaction not completed
2020-11-25 23:09:32.202 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 01 F9 C9 00
2020-11-25 23:09:32.203 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 01 F9 C9 00
2020-11-25 23:09:32.204 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:32.205 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:32.206 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.207 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0
2020-11-25 23:09:32.208 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:32.208 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT
2020-11-25 23:09:32.209 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report COLD_WHITE 249
2020-11-25 23:09:32.210 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.
2020-11-25 23:09:32.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cf2b63.
2020-11-25 23:09:32.211 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@1cf2b63.
2020-11-25 23:09:32.212 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1313 DONE
2020-11-25 23:09:32.213 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent
2020-11-25 23:09:32.214 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1
2020-11-25 23:09:32.215 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0
2020-11-25 23:09:32.216 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.217 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff false.
2020-11-25 23:09:32.218 [DEBUG] [wave.internal.protocol.SerialMessage] - Assembled message buffer = 01 0A 00 13 2B 03 33 03 03 25 85 5D
2020-11-25 23:09:32.219 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - NODE 43: Sending REQUEST Message = 01 0A 00 13 2B 03 33 03 03 25 85 5D
2020-11-25 23:09:32.220 [DEBUG] [ing.zwave.handler.ZWaveSerialHandler] - Message SENT
2020-11-25 23:09:32.221 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage started: TID 1314: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.222 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 06
2020-11-25 23:09:32.223 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.224 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=null[0], type=ACK[2], dest=255, callback=0, payload=
2020-11-25 23:09:32.225 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1314: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg: ACK
2020-11-25 23:09:32.226 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.227 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.228 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 04 01 13 01 E8
2020-11-25 23:09:32.230 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.231 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.232 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1314: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.233 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.234 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1314: [WAIT_RESPONSE] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.234 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Response[1], dest=255, callback=0, payload=01
2020-11-25 23:09:32.235 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: sentData successfully placed on stack.
2020-11-25 23:09:32.236 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1314: Advanced to WAIT_REQUEST
2020-11-25 23:09:32.237 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1314: Transaction not completed
2020-11-25 23:09:32.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty
2020-11-25 23:09:32.238 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 1 out at start. Holdoff false.
2020-11-25 23:09:32.248 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 18 00 13 85 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00 BB
2020-11-25 23:09:32.251 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.253 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.254 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction TID 1314: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.289 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking outstanding transactions: 1
2020-11-25 23:09:32.291 [DEBUG] [WaveSerialHandler$ZWaveReceiveThread] - Receive Message = 01 0C 00 04 00 2B 04 33 04 03 02 CA 00 24
2020-11-25 23:09:32.291 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Last transaction: TID 1314: [WAIT_REQUEST] priority=Get, requiresResponse=true, callback: 133
2020-11-25 23:09:32.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Checking TID 1314: (Callback 133)
2020-11-25 23:09:32.295 [DEBUG] [nal.protocol.ZWaveTransactionManager] - processReceiveMessage input 0<>128 : Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 03 02 CA 00
2020-11-25 23:09:32.298 [DEBUG] [.serialmessage.ZWaveCommandProcessor] - Callback match!
2020-11-25 23:09:32.305 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Correlated to TID 1314: callback 133
2020-11-25 23:09:32.306 [DEBUG] [ve.internal.protocol.ZWaveController] - Incoming Message: Message: class=SendData[19], type=Request[0], dest=0, callback=133, payload=85 00 00 02 00 C9 7F 7F 7F 7F 00 00 03 00 00 00 00 03 01 00 00
2020-11-25 23:09:32.308 [DEBUG] [l.serialmessage.SendDataMessageClass] - NODE 43: SendData Request. CallBack ID = 133, Status = Transmission complete and ACK received(0)
2020-11-25 23:09:32.309 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.311 [DEBUG] [nal.protocol.ZWaveTransactionManager] - TID 1314: Advanced to WAIT_DATA
2020-11-25 23:09:32.312 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: TID 1314: Transaction not completed
2020-11-25 23:09:32.316 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Received msg (0): Message: class=ApplicationCommandHandler[4], type=Request[0], dest=43, callback=0, payload=00 2B 04 33 04 03 02 CA 00
2020-11-25 23:09:32.317 [DEBUG] [nal.protocol.ZWaveTransactionManager] - lastTransaction null
2020-11-25 23:09:32.318 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Application Command Request (ALIVE:DONE)
2020-11-25 23:09:32.319 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: resetResendCount initComplete=true isDead=false
2020-11-25 23:09:32.321 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: Incoming command class COMMAND_CLASS_SWITCH_COLOR, endpoint 0
2020-11-25 23:09:32.322 [DEBUG] [ng.zwave.internal.protocol.ZWaveNode] - NODE 43: SECURITY not supported
2020-11-25 23:09:32.324 [DEBUG] [tocol.commandclass.ZWaveCommandClass] - NODE 43: Received COMMAND_CLASS_SWITCH_COLOR V1 SWITCH_COLOR_REPORT
2020-11-25 23:09:32.325 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report GREEN 2
2020-11-25 23:09:32.326 [DEBUG] [.commandclass.ZWaveColorCommandClass] - NODE 43: Color report finished {BLUE=2, WARM_WHITE=2, RED=2, COLD_WHITE=249, GREEN=2}
2020-11-25 23:09:32.327 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveColorValueEvent
2020-11-25 23:09:32.328 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got a value event from Z-Wave network, endpoint=0, command class=COMMAND_CLASS_SWITCH_COLOR, value=0
2020-11-25 23:09:32.330 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:color_color to 0,0,0 [HSBType]
2020-11-25 23:09:32.331 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Updating channel state zwave:device:04ca0044:node43:color_temperature to 0 [PercentType]
2020-11-25 23:09:32.334 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Commands processed 1.


Summary

2020-11-25 23:09:32.335 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Checking command org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11216c2. 2020-11-25 23:09:32.337 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: Command verified org.openhab.binding.zwave.internal.protocol.ZWaveCommandClassPayload@11216c2. 2020-11-25 23:09:32.338 [DEBUG] [nal.protocol.ZWaveTransactionManager] - NODE 43: notifyTransactionResponse TID:1314 DONE 2020-11-25 23:09:32.340 [DEBUG] [ding.zwave.handler.ZWaveThingHandler] - NODE 43: Got an event from Z-Wave network: ZWaveTransactionCompletedEvent 2020-11-25 23:09:32.341 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 1 2020-11-25 23:09:32.342 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction completed - outstandingTransactions 0 2020-11-25 23:09:32.343 [DEBUG] [nal.protocol.ZWaveTransactionManager] - ZWaveReceiveThread queue empty 2020-11-25 23:09:32.344 [DEBUG] [nal.protocol.ZWaveTransactionManager] - Transaction SendNextMessage 0 out at start. Holdoff fals/

alguevel · November 25, 2020, 10:36pm

Here is a shorter log file I don’t know how the first one is so big : rgbw2.log (67.6 KB)

alguevel · November 26, 2020, 11:37pm

any update on this ?
I think I’m quite close, I would need to understand how to set WARM_WHITE and COLD_WHITE to 0 despite the fact that the default channel don’t allow that

alguevel · November 28, 2020, 2:21am

up, hope someone can help me on this

alguevel · December 3, 2020, 9:47pm

Hello, anyone have an idea ?

I found a workaround suggestion : put to 0 the warm white and cold white channels, but these are not in the default channels available, so I don’t know how to apply it…