Zigbee bulb not changing colors

James_Kester · June 3, 2024, 10:37pm

The issue may be here?
Channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color failed to initialise converter

17:25:16.756 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6466, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=C0], messageTag=75, messageContents=00 75 00 07 00]
17:25:16.756 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=99 00 01 34 00 00 66 64 04 01 00 03 01 01 40 11 00 00 C0 75 05 00 75 00 07 00]
17:25:16.756 [WARN ] [l.converter.ZigBeeConverterColorColor] - A4C1381B88FD55A7: Device supports neither RGB color nor XY color
17:25:16.756 [INFO ] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color failed to initialise converter
17:25:16.757 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Channel initialisation complete
17:25:16.757 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Setting ONLINE/OFFLINE timeout interval to: 14430
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7 in 14430 seconds
17:25:16.757 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C1381B88FD55A7: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C1381B88FD55A7 queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> 6466/0, cluster=0033, TID=--, startIndex=0]]

17:25:16.603 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C138100ED6268B: Channel zigbee:device:3ea840067f:a4c138100ed6268b:A4C138100ED6268B_1_color updated to 267.87402,95.66929,55
17:25:16.603 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C138100ED6268B: Updating ZigBee channel state zigbee:device:3ea840067f:a4c138100ed6268b:A4C138100ED6268B_1_color to 267.87402,95.66929,55
17:25:16.603 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c138100ed6268b
17:25:16.603 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:3ea840067f:a4c138100ed6268b
17:25:16.603 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c138100ed6268b in 14430 seconds
17:25:16.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=97 90 01 59 00 66 40 8B 26 D6 0E 10 38 C1 A4 FF E8 00]
17:25:16.604 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:25:16.604 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4066, sourceEui=A4C138100ED6268B, lastHopLqi=255, lastHopRssi=-24, relayList=]
17:25:16.610 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=97 90 01 45 00 00 04 01 08 00 01 01 40 01 00 00 8E FF E9 66 40 FF FF 07 18 F0 0A 00 00 20 A0 02]
17:25:16.610 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:25:16.610 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=8E], lastHopLqi=255, lastHopRssi=-23, sender=4066, bindingIndex=255, addressIndex=255, messageContents=18 F0 0A 00 00 20 A0]
17:25:16.610 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4066/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8E, rssi=-23, lqi=FF, payload=18 F0 0A 00 00 20 A0]
17:25:16.610 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C138100ED6268B: Node update. NWK Address=NULL
17:25:16.610 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C138100ED6268B: Node 4066 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C138100ED6268B, NWK=----, endpoints=[]]
17:25:16.610 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=F0, commandId=10]
17:25:16.611 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 4066/1 -> 0000/1, cluster=0008, TID=F0, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=160]]]
17:25:16.611 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Level Control: 4066/1 -> 0000/1, cluster=0008, TID=F0, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=160]]]
17:25:16.611 [DEBUG] [l.converter.ZigBeeConverterColorColor] - A4C138100ED6268B: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=160, lastReportTime=Mon Jun 03 17:25:16 CDT 2024, implemented=true]
17:25:16.611 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C138100ED6268B: Channel zigbee:device:3ea840067f:a4c138100ed6268b:A4C138100ED6268B_1_color updated to 267.87402,95.66929,63
17:25:16.611 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C138100ED6268B: Updating ZigBee channel state zigbee:device:3ea840067f:a4c138100ed6268b:A4C138100ED6268B_1_color to 267.87402,95.66929,63
17:25:16.612 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c138100ed6268b
17:25:16.612 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:3ea840067f:a4c138100ed6268b
17:25:16.612 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c138100ed6268b in 14430 seconds
17:25:16.633 [INFO ] [openhab.event.ItemStateChangedEvent  ] - Item 'Generic_MQTT_Thing_Color' changed from 185,93,90 to 189,89,85
17:25:16.634 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C138100ED6268B: Command for channel zigbee:device:3ea840067f:a4c138100ed6268b:A4C138100ED6268B_1_color --> 189,89,85 [HSBType]
17:25:16.634 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Command for channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color --> 189,89,85 [HSBType]
17:25:16.634 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C13884A303C0EF: Command for channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color --> 189,89,85 [HSBType]
17:25:16.634 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: No handler found for zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color
17:25:16.676 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=97 90 01 59 00 89 4F EF C0 03 A3 84 38 C1 A4 C4 CD 00]
17:25:16.676 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:25:16.676 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4F89, sourceEui=A4C13884A303C0EF, lastHopLqi=196, lastHopRssi=-51, relayList=]
17:25:16.684 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=97 90 01 45 00 00 04 01 00 03 01 01 40 01 00 00 24 C4 CD 89 4F FF FF 07 18 A9 0A 01 00 20 F3 02]
17:25:16.684 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:25:16.684 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=24], lastHopLqi=196, lastHopRssi=-51, sender=4F89, bindingIndex=255, addressIndex=255, messageContents=18 A9 0A 01 00 20 F3]
17:25:16.684 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4F89/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=24, rssi=-51, lqi=C4, payload=18 A9 0A 01 00 20 F3]
17:25:16.684 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node update. NWK Address=NULL
17:25:16.684 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node 4F89 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C13884A303C0EF, NWK=----, endpoints=[]]
17:25:16.684 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=A9, commandId=10]
17:25:16.684 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Color Control: 4F89/1 -> 0000/1, cluster=0300, TID=A9, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, attributeValue=243]]]
17:25:16.685 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Color Control: 4F89/1 -> 0000/1, cluster=0300, TID=A9, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, attributeValue=243]]]
17:25:16.685 [DEBUG] [l.converter.ZigBeeConverterColorColor] - A4C13884A303C0EF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=243, lastReportTime=Mon Jun 03 17:25:16 CDT 2024, implemented=true]
17:25:16.685 [DEBUG] [erter.ZigBeeConverterColorTemperature] - A4C13884A303C0EF: ZigBee attribute reports ZclAttribute [cluster=Color Control, id=1, name=Current Saturation, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=243, lastReportTime=Mon Jun 03 17:25:16 CDT 2024, implemented=true]  on endpoint 1
17:25:16.685 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C13884A303C0EF: Channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color updated to 231.02362,95.66929,76
17:25:16.685 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C13884A303C0EF: Updating ZigBee channel state zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color to 231.02362,95.66929,76
17:25:16.685 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.686 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.686 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef in 14430 seconds
17:25:16.688 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=6, reTx=false, data=97 90 01 59 00 89 4F EF C0 03 A3 84 38 C1 A4 C4 CD 00]
17:25:16.688 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:25:16.688 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4F89, sourceEui=A4C13884A303C0EF, lastHopLqi=196, lastHopRssi=-51, relayList=]
17:25:16.694 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=6, reTx=false, data=97 90 01 3F 00 00 89 4F 04 01 00 03 01 01 40 11 00 00 20 70 00 00]
17:25:16.694 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:25:16.694 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=4F89, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=20], messageTag=70, status=EMBER_SUCCESS, messageContents=]
17:25:16.694 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=70 state=RX_ACK
17:25:16.694 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=70, state=RX_ACK, outstanding=3
17:25:16.694 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=4F89, TID=70, event=RX_ACK, state=ACKED
17:25:16.698 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=6, reTx=false, data=97 90 01 59 00 89 4F EF C0 03 A3 84 38 C1 A4 C4 CD 00]
17:25:16.698 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:25:16.698 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4F89, sourceEui=A4C13884A303C0EF, lastHopLqi=196, lastHopRssi=-51, relayList=]
17:25:16.704 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=6, reTx=false, data=97 90 01 45 00 00 04 01 08 00 01 01 40 01 00 00 25 C4 CD 89 4F FF FF 07 18 AA 0A 00 00 20 C2 02]
17:25:16.704 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:25:16.704 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=25], lastHopLqi=196, lastHopRssi=-51, sender=4F89, bindingIndex=255, addressIndex=255, messageContents=18 AA 0A 00 00 20 C2]
17:25:16.704 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4F89/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=25, rssi=-51, lqi=C4, payload=18 AA 0A 00 00 20 C2]
17:25:16.704 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node update. NWK Address=NULL
17:25:16.704 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node 4F89 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C13884A303C0EF, NWK=----, endpoints=[]]
17:25:16.704 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=AA, commandId=10]
17:25:16.704 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReportAttributesCommand [Level Control: 4F89/1 -> 0000/1, cluster=0008, TID=AA, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=194]]]
17:25:16.705 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ReportAttributesCommand [Level Control: 4F89/1 -> 0000/1, cluster=0008, TID=AA, reports=[AttributeReport [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, attributeValue=194]]]
17:25:16.705 [DEBUG] [l.converter.ZigBeeConverterColorColor] - A4C13884A303C0EF: ZigBee attribute reports ZclAttribute [cluster=Level Control, id=0, name=Current Level, dataType=UNSIGNED_8_BIT_INTEGER, lastValue=194, lastReportTime=Mon Jun 03 17:25:16 CDT 2024, implemented=true]
17:25:16.705 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C13884A303C0EF: Channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color updated to 231.02362,95.66929,76
17:25:16.705 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C13884A303C0EF: Updating ZigBee channel state zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color to 231.02362,95.66929,76
17:25:16.705 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.705 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.705 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef in 14430 seconds
17:25:16.708 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=97 90 01 59 00 89 4F EF C0 03 A3 84 38 C1 A4 C4 CD 00]
17:25:16.708 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:25:16.708 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4F89, sourceEui=A4C13884A303C0EF, lastHopLqi=196, lastHopRssi=-51, relayList=]
17:25:16.730 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=6, reTx=false, data=97 90 01 45 00 00 04 01 00 03 01 01 40 01 00 00 26 C4 CD 89 4F FF FF 05 18 70 0B 06 00 02]
17:25:16.730 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:25:16.730 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=26], lastHopLqi=196, lastHopRssi=-51, sender=4F89, bindingIndex=255, addressIndex=255, messageContents=18 70 0B 06 00]
17:25:16.730 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4F89/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=26, rssi=-51, lqi=C4, payload=18 70 0B 06 00]
17:25:16.730 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node update. NWK Address=NULL
17:25:16.730 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C13884A303C0EF: Node 4F89 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C13884A303C0EF, NWK=----, endpoints=[]]
17:25:16.731 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=70, commandId=11]
17:25:16.731 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DefaultResponse [Color Control: 4F89/1 -> 0000/1, cluster=0300, TID=70, commandIdentifier=6, statusCode=SUCCESS]
17:25:16.731 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: DefaultResponse [Color Control: 4F89/1 -> 0000/1, cluster=0300, TID=70, commandIdentifier=6, statusCode=SUCCESS]
17:25:16.731 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=A4C13884A303C0EF queueTime=621, state=COMPLETE, sendCnt=1, command=MoveToHueAndSaturationCommand [Color Control: 0000/0 -> 4F89/1, cluster=0300, TID=70, hue=134, saturation=224, transitionTime=10]]
17:25:16.731 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C13884A303C0EF: Channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color received SUCCESS in response to 190,88,84
17:25:16.731 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 2 outstanding
17:25:16.731 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C13884A303C0EF: Channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color updated to 190,88,84
17:25:16.731 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C13884A303C0EF: transactionComplete, state=COMPLETE, outstanding=0
17:25:16.731 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C13884A303C0EF: Updating ZigBee channel state zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color to 190,88,84
17:25:16.731 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=7, sleepy=0/3
17:25:16.732 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.732 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker cancelled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef
17:25:16.732 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c13884a303c0ef in 14430 seconds
17:25:16.732 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C13884A303C0EF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C13884A303C0EF queueTime=0, state=WAITING, sendCnt=0, command=MoveToLevelWithOnOffCommand [Level Control: 0000/0 -> 4F89/1, cluster=0008, TID=--, level=127, transitionTime=10]]
17:25:16.732 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=8, sleepy=0/3
17:25:16.732 [DEBUG] [.transaction.ZigBeeTransactionManager] - 4F89/1: Sending ZigBeeTransaction [ieeeAddress=A4C13884A303C0EF queueTime=0, state=WAITING, sendCnt=0, command=MoveToLevelWithOnOffCommand [Level Control: 0000/0 -> 4F89/1, cluster=0008, TID=74, level=127, transitionTime=10]]
17:25:16.733 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 3 outstanding
17:25:16.733 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: MoveToLevelWithOnOffCommand [Level Control: 0000/0 -> 4F89/1, cluster=0008, TID=74, level=127, transitionTime=10]
17:25:16.733 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=CLUSTER_SPECIFIC_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=74, commandId=4]
17:25:16.733 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=4F89/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=BF, rssi=--, lqi=--, payload=01 74 04 7F 0A 00]
17:25:16.733 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C13884A303C0EF: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C13884A303C0EF queueTime=0, state=WAITING, sendCnt=0, command=MoveToHueAndSaturationCommand [Color Control: 0000/0 -> 4F89/1, cluster=0300, TID=--, hue=198, saturation=254, transitionTime=10]]
17:25:16.733 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=4F89, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=BF], messageTag=74, messageContents=01 74 04 7F 0A 00]
17:25:16.733 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=8, sleepy=0/3
17:25:16.734 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=6, ackNum=1, reTx=false, data=98 00 01 34 00 00 89 4F 04 01 08 00 01 01 40 11 00 00 BF 74 06 01 74 04 7F 0A 00]
17:25:16.734 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=6, reTx=false, data=97 90 01 59 00 66 64 A7 55 FD 88 1B 38 C1 A4 FF DE 00]
17:25:16.734 [DEBUG] [.converter.ZigBeeBaseChannelConverter] - A4C13884A303C0EF: Channel zigbee:device:3ea840067f:a4c13884a303c0ef:A4C13884A303C0EF_1_color waiting for response to 280,100,50
17:25:16.734 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=6466, sourceEui=A4C1381B88FD55A7, lastHopLqi=255, lastHopRssi=-34, relayList=]
17:25:16.734 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:25:16.738 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=6, reTx=false, data=97 90 01 3F 00 00 66 64 04 01 00 03 01 01 40 11 00 00 23 73 00 00]
17:25:16.738 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:25:16.738 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6466, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=23], messageTag=73, status=EMBER_SUCCESS, messageContents=]
17:25:16.743 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=3, ackNum=7, reTx=false, data=98 84 01 34 00 00 24]
17:25:16.745 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=4, notRdy=false]
17:25:16.746 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=73 state=RX_ACK
17:25:16.745 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=24]
17:25:16.746 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=73, state=RX_ACK, outstanding=3
17:25:16.746 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=4, ackNum=7, reTx=false, data=97 90 01 59 00 66 64 A7 55 FD 88 1B 38 C1 A4 FF DE 00]
17:25:16.746 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=6466, TID=73, event=RX_ACK, state=ACKED
17:25:16.746 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=6466, sourceEui=A4C1381B88FD55A7, lastHopLqi=255, lastHopRssi=-34, relayList=]
17:25:16.746 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=5, notRdy=false]
17:25:16.753 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=5, ackNum=7, reTx=false, data=98 90 01 45 00 00 04 01 00 03 01 01 00 01 00 00 DA FF DE 66 64 FF FF 07 18 73 07 86 00 08 00 02]
17:25:16.753 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=6, notRdy=false]
17:25:16.753 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY], groupId=0, sequence=DA], lastHopLqi=255, lastHopRssi=-34, sender=6466, bindingIndex=255, addressIndex=255, messageContents=18 73 07 86 00 08 00]
17:25:16.753 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=6466/1, destinationAddress=0000/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=DA, rssi=-34, lqi=FF, payload=18 73 07 86 00 08 00]
17:25:16.753 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C1381B88FD55A7: Node update. NWK Address=NULL
17:25:16.753 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - A4C1381B88FD55A7: Node 6466 is not updated from ZigBeeNode [state=ONLINE, IEEE=A4C1381B88FD55A7, NWK=----, endpoints=[]]
17:25:16.753 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=73, commandId=7]
17:25:16.754 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ConfigureReportingResponse [Color Control: 6466/1 -> 0000/1, cluster=0300, TID=73, status=null, records=[Attribute Status Record [status=UNSUPPORTED_ATTRIBUTE, direction=false, attributeIdentifier=8]]]
17:25:16.754 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionCommand: ConfigureReportingResponse [Color Control: 6466/1 -> 0000/1, cluster=0300, TID=73, status=null, records=[Attribute Status Record [status=UNSUPPORTED_ATTRIBUTE, direction=false, attributeIdentifier=8]]]
17:25:16.754 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction complete: ZigBeeTransaction [ieeeAddress=A4C1381B88FD55A7 queueTime=205, state=COMPLETE, sendCnt=1, command=ConfigureReportingCommand [Color Control: 0000/0 -> 6466/1, cluster=0300, TID=73, records=[AttributeReportingConfigurationRecord [attributeDataType=ENUMERATION_8_BIT, attributeIdentifier=8, direction=0, minimumReportingInterval=1, maximumReportingInterval=7200]]]]
17:25:16.754 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Initializing channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorColor@bf77221
17:25:16.754 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerRemoved: 2 outstanding
17:25:16.754 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C1381B88FD55A7: transactionComplete, state=COMPLETE, outstanding=0
17:25:16.754 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=8, sleepy=0/3
17:25:16.754 [WARN ] [l.converter.ZigBeeConverterColorColor] - A4C1381B88FD55A7: Device supports neither RGB color nor XY color
17:25:16.755 [INFO ] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color failed to initialise device
17:25:16.755 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C1381B88FD55A7: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C1381B88FD55A7 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Color Control: 0000/0 -> 6466/1, cluster=0300, TID=--, identifiers=[7]]]
17:25:16.755 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=2, outstandingQueues=9, sleepy=0/3
17:25:16.755 [DEBUG] [.transaction.ZigBeeTransactionManager] - 6466/1: Sending ZigBeeTransaction [ieeeAddress=A4C1381B88FD55A7 queueTime=0, state=WAITING, sendCnt=0, command=ReadAttributesCommand [Color Control: 0000/0 -> 6466/1, cluster=0300, TID=75, identifiers=[7]]]
17:25:16.755 [DEBUG] [.transaction.ZigBeeTransactionManager] - transactionListenerAdded: 3 outstanding
17:25:16.755 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Color Control: 0000/0 -> 6466/1, cluster=0300, TID=75, identifiers=[7]]
17:25:16.755 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=75, commandId=0]
17:25:16.756 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0000/1, destinationAddress=6466/1, profile=0104, cluster=0300, addressMode=DEVICE, radius=8, apsSecurity=false, ackRequest=true, apsCounter=C0, rssi=--, lqi=--, payload=00 75 00 07 00]
17:25:16.756 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - ASH TX EZSP: EzspSendUnicastRequest [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=6466, apsFrame=EmberApsFrame [profileId=0104, clusterId=0300, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=C0], messageTag=75, messageContents=00 75 00 07 00]
17:25:16.756 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameData [frmNum=7, ackNum=6, reTx=false, data=99 00 01 34 00 00 66 64 04 01 00 03 01 01 40 11 00 00 C0 75 05 00 75 00 07 00]
17:25:16.756 [WARN ] [l.converter.ZigBeeConverterColorColor] - A4C1381B88FD55A7: Device supports neither RGB color nor XY color
17:25:16.756 [INFO ] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Channel zigbee:device:3ea840067f:a4c1381b88fd55a7:A4C1381B88FD55A7_1_color failed to initialise converter
17:25:16.757 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Channel initialisation complete
17:25:16.757 [DEBUG] [ing.zigbee.handler.ZigBeeThingHandler] - A4C1381B88FD55A7: Setting ONLINE/OFFLINE timeout interval to: 14430
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker added for thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker reset for handler with thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7
17:25:16.757 [DEBUG] [g.zigbee.handler.ZigBeeIsAliveTracker] - IsAlive Tracker scheduled task for thingUID=zigbee:device:3ea840067f:a4c1381b88fd55a7 in 14430 seconds
17:25:16.757 [DEBUG] [ee.transaction.ZigBeeTransactionQueue] - A4C1381B88FD55A7: Added transaction to queue, len=1, transaction=ZigBeeTransaction [ieeeAddress=A4C1381B88FD55A7 queueTime=0, state=WAITING, sendCnt=0, command=ManagementBindRequest [0000/0 -> 6466/0, cluster=0033, TID=--, startIndex=0]]
17:25:16.757 [DEBUG] [.transaction.ZigBeeTransactionManager] - Transaction Manager: Send Next transaction. outstandingTransactions=3, outstandingQueues=9, sleepy=0/3
17:25:16.765 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=6, ackNum=0, reTx=false, data=99 80 01 34 00 00 25]
17:25:16.765 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=7, notRdy=false]
17:25:16.765 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspSendUnicastResponse [networkId=0, status=EMBER_SUCCESS, sequence=25]
17:25:16.970 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=7, ackNum=0, reTx=false, data=99 90 01 59 00 66 40 8B 26 D6 0E 10 38 C1 A4 FF E8 00]
17:25:16.970 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=0, notRdy=false]
17:25:16.970 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4066, sourceEui=A4C138100ED6268B, lastHopLqi=255, lastHopRssi=-24, relayList=]
17:25:16.974 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=0, ackNum=0, reTx=false, data=99 90 01 3F 00 00 66 40 04 01 08 00 01 01 40 11 00 00 21 71 00 00]
17:25:16.974 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=1, notRdy=false]
17:25:16.974 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspMessageSentHandler [networkId=0, type=EMBER_OUTGOING_DIRECT, indexOrDestination=4066, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ADDRESS_DISCOVERY, EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=21], messageTag=71, status=EMBER_SUCCESS, messageContents=]
17:25:16.974 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX STA: msgTag=71 state=RX_ACK
17:25:16.974 [DEBUG] [.transaction.ZigBeeTransactionManager] - notifyTransactionProgress: TID=71, state=RX_ACK, outstanding=3
17:25:16.974 [DEBUG] [.zigbee.transaction.ZigBeeTransaction] - Transaction state changed: nwk=4066, TID=71, event=RX_ACK, state=ACKED
17:25:16.978 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=1, ackNum=0, reTx=false, data=99 90 01 59 00 66 40 8B 26 D6 0E 10 38 C1 A4 FF E9 00]
17:25:16.978 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingRouteRecordHandler [networkId=0, source=4066, sourceEui=A4C138100ED6268B, lastHopLqi=255, lastHopRssi=-23, relayList=]
17:25:16.978 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=2, notRdy=false]
17:25:16.986 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - <-- RX ASH frame: AshFrameData [frmNum=2, ackNum=0, reTx=false, data=99 90 01 45 00 00 04 01 08 00 01 01 40 01 00 00 8F FF E9 66 40 FF FF 05 18 71 0B 04 00 02]
17:25:16.986 [DEBUG] [le.ember.internal.ash.AshFrameHandler] - --> TX ASH frame: AshFrameAck [ackNum=3, notRdy=false]
17:25:16.986 [DEBUG] [.zigbee.dongle.ember.ZigBeeDongleEzsp] - RX EZSP: EzspIncomingMessageHandler [networkId=0, type=EMBER_INCOMING_UNICAST, apsFrame=EmberApsFrame [profileId=0104, clusterId=0008, sourceEndpoint=1, destinationEndpoint=1, options=[EMBER_APS_OPTION_ENABLE_ROUTE_DISCOVERY, EMBER_APS_OPTION_RETRY], groupId=0, sequence=8F], lastHopLqi=255, lastHopRssi=-23, sender=4066, bindingIndex=255, addressIndex=255, messageContents=18 71 0B 04 00]
17:25:16.986 [DEBUG] [rtsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=4066/1, destinationAddress=0000/1, profile=0104, cluster=0008, addressMode=DEVICE, radius=0, apsSecurity=false, ackRequest=false, apsCounter=8F, rssi=-23, lqi=FF, payload=18 71 0B 04 00]

chris · June 4, 2024, 2:54am

See if you can configure the channel. There should be an option called “Color Control Method” - try setting it to HUE - or XY. It defaults to Auto, but in some cases this causes a problem if the bulb doesn’t report correctly. To get to the configuration, click on the Channel Details button in the channels list.

James_Kester · June 4, 2024, 2:57pm

That is how I have it configured at least I think. I can post screens of the configuration tonight.

I have the colors coming across (via MQTT) as HSB values (H, S, B). The working lights don’t seem to care, they change with both (R, G, B) or (H, S, B). I don’t think I have tried XY, but I did see elsewhere in the log when I had it configured as RGB, there were messages like: value for brightness is out of range (should be between 0-100) So I am pretty sure the bulb is looking for HSB.

chris · June 4, 2024, 8:03pm

I think it’s worth checking. I’m not talking about the item configuration here - this is the channel configuration - it’s the commands that the binding uses to communicate with the device. RGB is not an option - it’s either HUE, or XY. The binding converts to the appropriate commands for the device, but the problem is that the binding for some reason cannot detect what commands this device requires.

James_Kester · June 5, 2024, 12:54pm

this channel has no configuration?

chris · June 5, 2024, 8:13pm

The first image is not related since it’s a different thing completely.

I’m not sure why it has no configuration - possibly because it isn’t initialised. It looks like it might be a catch 22 - you can’t configure this as it’s not initialised, but it’s not initialised because it can’t detect the supported colour commands.

I think what I’ll do is change this so that instead of failing the initialisation if it can’t properly detect the devices features, it instead defaults to using Hue commands.

chris · June 5, 2024, 8:31pm

James_Kester · June 5, 2024, 8:43pm

This is great! thanks for sticking with me on this dumb bulb problem!

So now it should initialize with a default configuration that may not work, but then I should be able to adjust with the channel configuration?

I will try this tonight.

chris · June 5, 2024, 8:53pm

Yes, hopefully it will initialise and work since it probably does support the HUE command set. If not, hopefully you will be able to change to the XY commands.

You’ll need to be using the latest snapshot binding - I’m not 100% sure when the snapshots are updated - I think it’s overnight European time, so you might find it doesn’t flow through the system until tomorrow for you (I assume you’re in the US based on the times you’re responding).

James_Kester · June 5, 2024, 9:06pm

Yes I am in the US, and I believe the bulb is supposed to support HUE commands. I will keep my eye on the 4.2.0-SNAPSHOT.

Thanks again.

James_Kester · June 6, 2024, 12:50pm

It works! thanks again for the fix!

system · July 18, 2024, 4:50am

This topic was automatically closed 41 days after the last reply. New replies are no longer allowed.