Issue adding Sunricher CCT Lighting

Question

Issue adding Sunricher CCT Lighting

Closed this issue 4 years ago · 3 comments

Outline

Issue adding Sunricher CCT Lighting (2 channel LED, WW and CW), there an exception in logs related to it:
com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolParseException: Packet checksum failed

Configuration

Configuration	Description
Coordinator used	TI CC2531EMK
openHAB version	2.5.0 (installed via packet manager)
Hardware	Raspberry Pi 3 B+
Memory	1GB
Java version	1.8.0_152-b76
Devices	2x OSRAM Plug 01 (7CB03EAA00B0C79E, 7CB03EAA0A0A4F6B), 1x Sunricher CCT Lighting (000D6F00137473A9)

Logs

events.log

2020-11-15 21:06:31.132 [hingStatusInfoChangedEvent] - 'zigbee:device:b72bea66:000d6f00137473a9' changed from UNINITIALIZED to INITIALIZING
2020-11-15 21:06:31.158 [hingStatusInfoChangedEvent] - 'zigbee:device:b72bea66:000d6f00137473a9' changed from INITIALIZING to UNKNOWN
2020-11-15 21:06:31.278 [me.event.ThingUpdatedEvent] - Thing 'zigbee:device:b72bea66:000d6f00137473a9' has been updated.
2020-11-15 21:06:31.294 [nt.FirmwareStatusInfoEvent] - Firmware status of thing zigbee:device:b72bea66:000d6f00137473a9 changed to UNKNOWN.
2020-11-15 21:06:31.313 [me.event.ThingUpdatedEvent] - Thing 'zigbee:device:b72bea66:000d6f00137473a9' has been updated.
2020-11-15 21:08:19.380 [hingStatusInfoChangedEvent] - 'zigbee:device:b72bea66:000d6f00137473a9' changed from UNKNOWN to ONLINE
2020-11-15 21:08:34.544 [.ItemChannelLinkAddedEvent] - Link 'SunricherCCTLighting_ColorTemperature-zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_colortemperature' has been added.
2020-11-15 21:08:43.609 [.ItemChannelLinkAddedEvent] - Link 'SunricherCCTLighting_LevelControl-zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_dimmer' has been added.

openhab.log

2020-11-15 21:06:01.807 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F00137473A9: Discovery: Starting discovery for existing device
2020-11-15 21:06:01.810 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A0A4F6B: Discovery: Starting discovery for existing device
2020-11-15 21:06:01.817 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00B0C79E: Starting ZigBee device discovery
2020-11-15 21:06:01.821 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00B0C79E: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66
2020-11-15 21:06:01.821 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 000D6F00137473A9: Starting ZigBee device discovery
2020-11-15 21:06:01.816 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - Starting ZigBee scan for zigbee:coordinator_cc2531:b72bea66
2020-11-15 21:06:01.825 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F00137473A9: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66
2020-11-15 21:06:01.826 [INFO ] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A0A4F6B: Starting ZigBee device discovery
2020-11-15 21:06:01.828 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - Permit join to 65532/0 for 60 seconds.
2020-11-15 21:06:01.831 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A0A4F6B: Creating ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66
2020-11-15 21:06:01.831 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery start
2020-11-15 21:06:01.835 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 65532/0, cluster=0036, TID=F4, permitDuration=60, tcSignificance=true]
2020-11-15 21:06:01.837 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery using basic cluster on endpoint 32474/3
2020-11-15 21:06:01.840 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA0A0A4F6B: ZigBee node property discovery start
2020-11-15 21:06:01.840 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65532/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=7D, payload=00 3C 01]
2020-11-15 21:06:01.847 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery start
2020-11-15 21:06:01.852 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery using basic cluster on endpoint 29117/1
2020-11-15 21:06:01.852 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA0A0A4F6B: ZigBee node property discovery using basic cluster on endpoint 5131/3
2020-11-15 21:06:01.869 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=F3, permitDuration=60, tcSignificance=true]
2020-11-15 21:06:01.876 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=7E, payload=00 3C 01]
2020-11-15 21:06:01.902 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 5131/3, cluster=0000, TID=F5, identifiers=[5, 4, 3, 2, 6, 0, 1]]
2020-11-15 21:06:01.905 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=245, commandId=0]
2020-11-15 21:06:01.910 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=5131/3, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=7F, payload=00 F5 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
2020-11-15 21:06:01.925 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00]
2020-11-15 21:06:01.927 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=--, status=SUCCESS]
2020-11-15 21:06:01.951 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=F6, permitDuration=60, tcSignificance=true]
2020-11-15 21:06:01.953 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=80, payload=00 3C 01]
2020-11-15 21:06:01.983 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 32474/3, cluster=0000, TID=F0, identifiers=[5, 4, 3, 2, 6, 0, 1]]
2020-11-15 21:06:01.984 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=240, commandId=0]
2020-11-15 21:06:01.986 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=32474/3, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=81, payload=00 F0 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
2020-11-15 21:06:02.000 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00]
2020-11-15 21:06:02.002 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=--, status=SUCCESS]
2020-11-15 21:06:02.040 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ReadAttributesCommand [Basic: 0/0 -> 32474/3, cluster=0000, TID=F7, identifiers=[5, 4, 3, 2, 6, 0, 1]]
2020-11-15 21:06:02.042 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=CLIENT_TO_SERVER, disableDefaultResponse=false, manufacturerCode=0, sequenceNumber=247, commandId=0]
2020-11-15 21:06:02.044 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/1, destinationAddress=32474/3, profile=0104, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=82, payload=00 F7 00 05 00 04 00 03 00 02 00 06 00 00 00 01 00]
2020-11-15 21:06:02.072 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=5131/3, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=18 F5 01 05 00 00 42 07 50 6C 75 67 20 30 31 04 00 00 42 05 4F 53 52 41 4D 03 00 00 20 01 02 00 00 20 02 06 00 00 42 10 32 30 31 34 30 33 33 31 44 45 4F 53 2A 2A 2A 2A 00 00 00 20 01 01 00 00 20 09]
2020-11-15 21:06:02.075 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=245, commandId=1]
2020-11-15 21:06:02.076 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 5131/3 -> 0/1, cluster=0000, TID=F5, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=SUCCESS, attributeValue=Plug 01], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=OSRAM], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=SUCCESS, attributeValue=2], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=SUCCESS, attributeValue=20140331DEOS****], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=SUCCESS, attributeValue=9]]]
2020-11-15 21:06:02.080 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA0A0A4F6B: ZigBee node property discovery using OTA cluster on endpoint 5131/3
2020-11-15 21:06:02.082 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA0A0A4F6B: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=Plug 01, zigbee_networkaddress=5131, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, MAINS, DISPOSABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020509, zigbee_applicationVersion=9}
2020-11-15 21:06:02.085 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA0A0A4F6B: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66, label 'OSRAM Plug 01'
2020-11-15 21:06:02.415 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA0A0A4F6B: ZigBee saving network state complete.
2020-11-15 21:06:03.342 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery using OTA cluster on endpoint 32474/3
2020-11-15 21:06:03.346 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=Plug 01, zigbee_networkaddress=32474, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, MAINS, DISPOSABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=12}
2020-11-15 21:06:03.351 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00B0C79E: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66, label 'OSRAM Plug 01'
2020-11-15 21:06:03.383 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery using OTA cluster on endpoint 29117/1
2020-11-15 21:06:03.387 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x100b, modelId=CCT Lighting, zigbee_networkaddress=29117, zigbee_powersource=MAINS, zigbee_stkversion=0, zigbee_datecode=NULL, zigbee_zclversion=3, vendor=Sunricher, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=0, firmwareVersion=0x0000001F, zigbee_applicationVersion=0}
2020-11-15 21:06:03.392 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F00137473A9: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66, label 'Sunricher CCT Lighting'
2020-11-15 21:06:03.759 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00B0C79E: ZigBee saving network state complete.
2020-11-15 21:06:03.824 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F00137473A9: ZigBee saving network state complete.
2020-11-15 21:06:11.445 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=32474/3, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=18 EB 01 05 00 00 42 07 50 6C 75 67 20 30 31 04 00 00 42 05 4F 53 52 41 4D 03 00 00 20 01 02 00 00 20 02 06 00 00 42 10 32 30 31 34 30 33 33 31 44 45 4F 53 2A 2A 2A 2A 00 00 00 20 01 01 00 00 20 0C]
2020-11-15 21:06:11.448 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=235, commandId=1]
2020-11-15 21:06:11.451 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 32474/3 -> 0/1, cluster=0000, TID=EB, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=SUCCESS, attributeValue=Plug 01], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=OSRAM], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=SUCCESS, attributeValue=2], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=SUCCESS, attributeValue=20140331DEOS****], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=SUCCESS, attributeValue=12]]]
2020-11-15 21:06:11.915 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=32474/3, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=18 EB 01 05 00 00 42 07 50 6C 75 67 20 30 31 04 00 00 42 05 4F 53 52 41 4D 03 00 00 20 01 02 00 00 20 02 06 00 00 42 10 32 30 31 34 30 33 33 31 44 45 4F 53 2A 2A 2A 2A 00 00 00 20 01 01 00 00 20 0C]
2020-11-15 21:06:11.918 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=235, commandId=1]
2020-11-15 21:06:11.922 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 32474/3 -> 0/1, cluster=0000, TID=EB, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=SUCCESS, attributeValue=Plug 01], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=OSRAM], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=SUCCESS, attributeValue=2], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=SUCCESS, attributeValue=20140331DEOS****], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=SUCCESS, attributeValue=12]]]
2020-11-15 21:06:12.308 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=32474/3, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=18 EB 01 05 00 00 42 07 50 6C 75 67 20 30 31 04 00 00 42 05 4F 53 52 41 4D 03 00 00 20 01 02 00 00 20 02 06 00 00 42 10 32 30 31 34 30 33 33 31 44 45 4F 53 2A 2A 2A 2A 00 00 00 20 01 01 00 00 20 0C]
2020-11-15 21:06:12.311 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=235, commandId=1]
2020-11-15 21:06:12.314 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 32474/3 -> 0/1, cluster=0000, TID=EB, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=SUCCESS, attributeValue=Plug 01], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=OSRAM], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=SUCCESS, attributeValue=2], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=SUCCESS, attributeValue=20140331DEOS****], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=SUCCESS, attributeValue=12]]]
2020-11-15 21:06:12.600 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=32474/3, destinationAddress=0/1, profile=0104, cluster=0000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=18 F0 01 05 00 00 42 07 50 6C 75 67 20 30 31 04 00 00 42 05 4F 53 52 41 4D 03 00 00 20 01 02 00 00 20 02 06 00 00 42 10 32 30 31 34 30 33 33 31 44 45 4F 53 2A 2A 2A 2A 00 00 00 20 01 01 00 00 20 0C]
2020-11-15 21:06:12.604 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX ZCL: ZclHeader [frameType=ENTIRE_PROFILE_COMMAND, manufacturerSpecific=false, direction=SERVER_TO_CLIENT, disableDefaultResponse=true, manufacturerCode=0, sequenceNumber=240, commandId=1]
2020-11-15 21:06:12.608 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ReadAttributesResponse [Basic: 32474/3 -> 0/1, cluster=0000, TID=F0, records=[ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=5, status=SUCCESS, attributeValue=Plug 01], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=4, status=SUCCESS, attributeValue=OSRAM], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=3, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=2, status=SUCCESS, attributeValue=2], ReadAttributeStatusRecord [attributeDataType=CHARACTER_STRING, attributeIdentifier=6, status=SUCCESS, attributeValue=20140331DEOS****], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=0, status=SUCCESS, attributeValue=1], ReadAttributeStatusRecord [attributeDataType=UNSIGNED_8_BIT_INTEGER, attributeIdentifier=1, status=SUCCESS, attributeValue=12]]]
2020-11-15 21:06:13.984 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery using OTA cluster on endpoint 29117/1
2020-11-15 21:06:13.988 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x100b, modelId=CCT Lighting, zigbee_networkaddress=29117, zigbee_powersource=MAINS, zigbee_stkversion=0, zigbee_datecode=NULL, zigbee_zclversion=3, vendor=Sunricher, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=0, firmwareVersion=0x0000001F, zigbee_applicationVersion=0}
2020-11-15 21:06:13.992 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 000D6F00137473A9: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66, label 'Sunricher CCT Lighting'
2020-11-15 21:06:14.091 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery using OTA cluster on endpoint 32474/3
2020-11-15 21:06:14.095 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 7CB03EAA00B0C79E: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0xbbaa, modelId=Plug 01, zigbee_networkaddress=32474, zigbee_powersource=MAINS, zigbee_stkversion=2, zigbee_datecode=20140331DEOS****, zigbee_zclversion=1, vendor=OSRAM, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[RECHARGABLE_BATTERY, MAINS, DISPOSABLE_BATTERY], hardwareVersion=1, firmwareVersion=0x01020412, zigbee_applicationVersion=12}
2020-11-15 21:06:14.100 [DEBUG] [bee.discovery.ZigBeeDiscoveryService] - 7CB03EAA00B0C79E: Update ZigBee device zigbee:device with bridge zigbee:coordinator_cc2531:b72bea66, label 'OSRAM Plug 01'
2020-11-15 21:06:14.399 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F00137473A9: ZigBee saving network state complete.
2020-11-15 21:06:14.475 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 7CB03EAA00B0C79E: ZigBee saving network state complete.
2020-11-15 21:06:26.784 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=2816/0, destinationAddress=0/0, profile=0000, cluster=0013, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=0B 00 0B A9 73 74 13 00 6F 0D 00 8E]
2020-11-15 21:06:26.787 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [2816/0 -> 0/0, cluster=0013, TID=--, nwkAddrOfInterest=2816, ieeeAddr=000D6F00137473A9, capability=142]
2020-11-15 21:06:26.791 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Updating node NWK=2816
2020-11-15 21:06:26.795 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Node 2816 update
2020-11-15 21:06:26.804 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=2816/0, destinationAddress=65535/0, profile=0000, cluster=0013, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=FF 00 0B A9 73 74 13 00 6F 0D 00 8E]
2020-11-15 21:06:26.807 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: DeviceAnnounce [2816/0 -> 65535/0, cluster=0013, TID=--, nwkAddrOfInterest=2816, ieeeAddr=000D6F00137473A9, capability=142]
2020-11-15 21:06:26.812 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Updating node NWK=2816
2020-11-15 21:06:26.815 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Node 2816 update
2020-11-15 21:06:26.818 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Node 2816 is not updated
2020-11-15 21:06:27.165 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F00137473A9: ZigBee saving network state complete.
2020-11-15 21:06:28.705 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementPermitJoiningRequest [0/0 -> 0/0, cluster=0036, TID=F6, permitDuration=60, tcSignificance=true]
2020-11-15 21:06:28.710 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0036, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=83, payload=00 3C 01]
2020-11-15 21:06:28.755 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8036, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00]
2020-11-15 21:06:28.758 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementPermitJoiningResponse [0/0 -> 0/0, cluster=8036, TID=--, status=SUCCESS]
2020-11-15 21:06:31.154 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Initializing ZigBee thing handler zigbee:device:b72bea66:000d6f00137473a9
2020-11-15 21:06:31.158 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Coordinator status changed to ONLINE.
2020-11-15 21:06:31.160 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Coordinator is ONLINE. Starting device initialisation.
2020-11-15 21:06:31.162 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: NetworkAddressRequest [0/0 -> 65533/0, cluster=0000, TID=F8, ieeeAddr=000D6F00137473A9, requestType=0, startIndex=0]
2020-11-15 21:06:31.165 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=65533/0, profile=0000, cluster=0000, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=84, payload=00 A9 73 74 13 00 6F 0D 00 00 00]
2020-11-15 21:06:31.172 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Start initialising ZigBee Thing handler
2020-11-15 21:06:31.175 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery start
2020-11-15 21:06:31.177 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery using basic cluster on endpoint 2816/1
2020-11-15 21:06:31.179 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery using OTA cluster on endpoint 2816/1
2020-11-15 21:06:31.182 [DEBUG] [scovery.ZigBeeNodePropertyDiscoverer] - 000D6F00137473A9: ZigBee node property discovery complete: {zigbee_logicaltype=ROUTER, zigbee_powerlevel=FULL, zigbee_manufacturercode=0x100b, modelId=CCT Lighting, zigbee_networkaddress=2816, zigbee_powersource=MAINS, zigbee_stkversion=0, zigbee_datecode=NULL, zigbee_zclversion=3, vendor=Sunricher, zigbee_powermode=RECEIVER_ON_IDLE, zigbee_powersources=[MAINS], hardwareVersion=0, firmwareVersion=0x0000001F, zigbee_applicationVersion=0}
2020-11-15 21:06:31.241 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementRoutingRequest [0/0 -> 0/0, cluster=0032, TID=F9, startIndex=0]
2020-11-15 21:06:31.263 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0032, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=85, payload=00 00]
2020-11-15 21:06:31.278 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Checking endpoint 1 channels
2020-11-15 21:06:31.292 [DEBUG] [er.ZigBeeChannelConverterFactoryImpl] - 000D6F00137473A9: Removing channel zigbee:switch_onoff in favor of zigbee:switch_level
2020-11-15 21:06:31.295 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Checking endpoint 242 channels
2020-11-15 21:06:31.303 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Dynamically created 2 channels
2020-11-15 21:06:31.305 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Updating thing definition as channels have changed from [] to [zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_colortemperature, zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_dimmer]
2020-11-15 21:06:31.310 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8032, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 02 00 02 DA 7E 02 FE FF BD 71 00 BD 71]
2020-11-15 21:06:31.313 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Initializing device
2020-11-15 21:06:31.313 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=--, status=SUCCESS, routingTableEntries=2, startIndex=0, routingTableList=[RoutingTable [destinationAddress=32474, status=DISCOVERY_FAILED, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=65534], RoutingTable [destinationAddress=29117, status=ACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=29117]]]
2020-11-15 21:06:31.315 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Initializing channel zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_colortemperature with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterColorTemperature@32327f
2020-11-15 21:06:31.319 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=FA, startIndex=0]
2020-11-15 21:06:31.321 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8032, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 02 00 02 DA 7E 02 FE FF BD 71 00 BD 71]
2020-11-15 21:06:31.321 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0031, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=86, payload=00 00]
2020-11-15 21:06:31.324 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementRoutingResponse [0/0 -> 0/0, cluster=8032, TID=--, status=SUCCESS, routingTableEntries=2, startIndex=0, routingTableList=[RoutingTable [destinationAddress=32474, status=DISCOVERY_FAILED, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=65534], RoutingTable [destinationAddress=29117, status=ACTIVE, memoryConstrained=false, manyToOne=false, routeRecordRequired=false, nextHopAddress=29117]]]
2020-11-15 21:06:31.369 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8031, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 04 00 03 36 63 71 26 0F 67 55 9B 9E C7 B0 00 AA 3E B0 7C DA 7E 15 02 01 01 36 63 71 26 0F 67 55 9B FF FF FF FF FF FF FF FF BD 71 15 02 01 01 36 63 71 26 0F 67 55 9B 6B 4F 0A 0A AA 3E B0 7C 0B 14 39 02 FF 02]
2020-11-15 21:06:31.374 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=--, status=SUCCESS, neighborTableEntries=4, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=7CB03EAA00B0C79E, networkAddress=32474, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=1], NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=FFFFFFFFFFFFFFFF, networkAddress=29117, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=1], NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=7CB03EAA0A0A4F6B, networkAddress=5131, deviceType=ROUTER, rxOnWhenIdle=UNKNOWN, relationship=UNKNOWN, permitJoining=UNKNOWN, depth=255, lqi=2]]]
2020-11-15 21:06:31.378 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX CMD: ManagementLqiRequest [0/0 -> 0/0, cluster=0031, TID=FB, startIndex=3]
2020-11-15 21:06:31.380 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - TX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=0031, addressMode=DEVICE, radius=31, apsSecurity=false, apsCounter=87, payload=00 03]
2020-11-15 21:06:31.383 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8031, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 04 00 03 36 63 71 26 0F 67 55 9B 9E C7 B0 00 AA 3E B0 7C DA 7E 15 02 01 01 36 63 71 26 0F 67 55 9B FF FF FF FF FF FF FF FF BD 71 15 02 01 01 36 63 71 26 0F 67 55 9B 6B 4F 0A 0A AA 3E B0 7C 0B 14 39 02 FF 02]
2020-11-15 21:06:31.389 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=--, status=SUCCESS, neighborTableEntries=4, startIndex=0, neighborTableList=[NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=7CB03EAA00B0C79E, networkAddress=32474, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=1], NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=FFFFFFFFFFFFFFFF, networkAddress=29117, deviceType=ROUTER, rxOnWhenIdle=RX_ON, relationship=CHILD, permitJoining=UNKNOWN, depth=1, lqi=1], NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=7CB03EAA0A0A4F6B, networkAddress=5131, deviceType=ROUTER, rxOnWhenIdle=UNKNOWN, relationship=UNKNOWN, permitJoining=UNKNOWN, depth=255, lqi=2]]]
2020-11-15 21:06:31.392 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B001938A5E3: Updating node NWK=0
2020-11-15 21:06:31.396 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 00124B001938A5E3: Node 0 update
2020-11-15 21:06:31.422 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8031, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 04 03 01 36 63 71 26 0F 67 55 9B A9 73 74 13 00 6F 0D 00 00 0B 39 02 FF 01]
2020-11-15 21:06:31.425 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=--, status=SUCCESS, neighborTableEntries=4, startIndex=3, neighborTableList=[NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=000D6F00137473A9, networkAddress=2816, deviceType=ROUTER, rxOnWhenIdle=UNKNOWN, relationship=UNKNOWN, permitJoining=UNKNOWN, depth=255, lqi=1]]]
2020-11-15 21:06:31.428 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=0/0, destinationAddress=0/0, profile=0000, cluster=8031, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 04 03 01 36 63 71 26 0F 67 55 9B A9 73 74 13 00 6F 0D 00 00 0B 39 02 FF 01]
2020-11-15 21:06:31.432 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: ManagementLqiResponse [0/0 -> 0/0, cluster=8031, TID=--, status=SUCCESS, neighborTableEntries=4, startIndex=3, neighborTableList=[NeighborTable [extendedPanId=9B55670F26716336, extendedAddress=000D6F00137473A9, networkAddress=2816, deviceType=ROUTER, rxOnWhenIdle=UNKNOWN, relationship=UNKNOWN, permitJoining=UNKNOWN, depth=255, lqi=1]]]
2020-11-15 21:06:31.660 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 00124B001938A5E3: ZigBee saving network state complete.
2020-11-15 21:06:32.891 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX APS: ZigBeeApsFrame [sourceAddress=2816/0, destinationAddress=0/0, profile=0000, cluster=8000, addressMode=null, radius=0, apsSecurity=false, apsCounter=--, payload=00 00 A9 73 74 13 00 6F 0D 00 00 0B]
2020-11-15 21:06:32.896 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - RX CMD: NetworkAddressResponse [2816/0 -> 0/0, cluster=8000, TID=--, status=SUCCESS, ieeeAddrRemoteDev=000D6F00137473A9, nwkAddrRemoteDev=2816, startIndex=null, nwkAddrAssocDevList=[]]
2020-11-15 21:06:32.899 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Updating node NWK=2816
2020-11-15 21:06:32.901 [ERROR] [531.network.packet.ZToolPacketStream] - Packet parsing failed due to exception.
com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolParseException: Packet checksum failed
	at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketStream.parsePacket(ZToolPacketStream.java:141) [bundleFile:?]
	at com.zsmartsystems.zigbee.dongle.cc2531.network.packet.ZToolPacketParser.run(ZToolPacketParser.java:107) [bundleFile:?]
	at java.lang.Thread.run(Thread.java:748) [?:1.8.0_152]
2020-11-15 21:06:32.903 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Node 2816 update
2020-11-15 21:06:32.908 [DEBUG] [tsystems.zigbee.ZigBeeNetworkManager] - 000D6F00137473A9: Node 2816 is not updated
2020-11-15 21:06:43.319 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Initializing channel zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_dimmer with org.openhab.binding.zigbee.internal.converter.ZigBeeConverterSwitchLevel@f5cc09
2020-11-15 21:06:55.324 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 000D6F00137473A9: Failed to bind level control cluster
2020-11-15 21:07:07.328 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 000D6F00137473A9: Failed to bind on off control cluster
2020-11-15 21:07:07.332 [ERROR] [converter.ZigBeeConverterSwitchLevel] - 000D6F00137473A9: Error opening device level controls
2020-11-15 21:07:07.335 [INFO ] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Channel zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_dimmer failed to initialise device
2020-11-15 21:07:19.342 [DEBUG] [converter.ZigBeeConverterSwitchLevel] - 000D6F00137473A9: Level control initialized as server
2020-11-15 21:08:07.355 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Channel initialisation complete
2020-11-15 21:08:07.358 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - Setting ONLINE/OFFLINE timeout interval to: 1830
2020-11-15 21:08:07.361 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Add IsAlive Tracker for thingUID=zigbee:device:b72bea66:000d6f00137473a9
2020-11-15 21:08:07.364 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Reset timeout for handler with thingUID=zigbee:device:b72bea66:000d6f00137473a9
2020-11-15 21:08:07.366 [DEBUG] [.zigbee.handler.ZigBeeIsAliveTracker] - Scheduling timeout task for thingUID=zigbee:device:b72bea66:000d6f00137473a9 in 1830 seconds
2020-11-15 21:08:19.372 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Error getting binding table
2020-11-15 21:08:19.377 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Polling initialised at 939647ms
2020-11-15 21:08:19.386 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Done initialising ZigBee Thing handler
2020-11-15 21:08:19.742 [DEBUG] [ding.zigbee.internal.ZigBeeDataStore] - 000D6F00137473A9: ZigBee saving network state complete.
2020-11-15 21:08:34.542 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Channel zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_colortemperature linked - polling started.
2020-11-15 21:08:43.607 [DEBUG] [ng.zigbee.handler.ZigBeeThingHandler] - 000D6F00137473A9: Channel zigbee:device:b72bea66:000d6f00137473a9:000D6F00137473A9_1_dimmer linked - polling started.

Answer 1 · 2020-11-15T22:04:30.000Z

Issue adding Sunricher CCT Lighting (2 channel LED, WW and CW)

What is the problem though?

The error you mention is not an issue - it's just a checksum failure. Please describe the problem you are having with the device -or is it really just the checksum error you're reporting?

Answer 2 · 2020-11-15T22:27:14.000Z

Controller is not initialized, zigbee_device_initialised is always false despite it also showing "Online".
After the controller is added, there's no further communications in either direction from the looks of it,
openhab is unaware of the state of the controller (on/off/dimming, color) and any changes to color or dimming have no affect on controller.

Answer 3 · 2020-11-16T20:05:17.000Z

Found the original documentation from Manufacturer instead of a resellers cut version, it had different workflow than describe by reseller - and it actually works now.

Model name SR-ZG9101FA-CCT, Operation 3 is for pairing with ZigBee controller like TI CC2531EMK