Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Support for LDS ZBT-CCTSwitch-D0001 #209

Closed
ad1217 opened this issue Dec 5, 2019 · 11 comments · Fixed by #211
Closed

Support for LDS ZBT-CCTSwitch-D0001 #209

ad1217 opened this issue Dec 5, 2019 · 11 comments · Fixed by #211

Comments

@ad1217
Copy link

ad1217 commented Dec 5, 2019 •
edited

This is a 4 button remote that came from an EcoSmart Kit sold by Home Depot. It pairs with ZHA and presents its battery level, but produces nothing in the log when buttons are pressed. I have little experience with Zigbee, so I'm not sure where to go from here.

FCC ID is 2AB2Q6ARCZABZH if that helps.

Quirk Stub 
"""Device handler for LDS ZBT-CCTSwitch-D001."""
from zigpy.profiles import zha
from zigpy.quirks import CustomDevice
from zigpy.zcl.clusters.general import (
    Basic,
    Groups,
    PowerConfiguration,
    Identify,
    LevelControl,
    Ota,
    OnOff,
)
from zigpy.zcl.clusters.lighting import Color
from zigpy.zcl.clusters.lightlink import LightLink


from ..const import (
    DEVICE_TYPE,
    ENDPOINTS,
    INPUT_CLUSTERS,
    MODELS_INFO,
    OUTPUT_CLUSTERS,
    PROFILE_ID,
 )

MANUFACTURER_SPECIFIC_CLUSTER_ID_1 = 0xFD01  # decimal = 64769


class LDSZBTCCTSwitch(CustomDevice):
    """Custom device representing LDS ZBT-CCTSwitch-D001."""

    signature = {
        #  <SimpleDescriptor endpoint=1 profile=260 device_type=2048
        #  device_version=1
        #  input_clusters=[0, 1, 3, 4096, 64769]
        #  output_clusters=[3, 4, 6, 8, 25, 768, 4096]>
        MODELS_INFO: [("LDS", "ZBT-CCTSwitch-D0001")],
        ENDPOINTS: {
            1: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.COLOR_CONTROLLER,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    PowerConfiguration.cluster_id,
                    Identify.cluster_id,
                    LightLink.cluster_id,
                    MANUFACTURER_SPECIFIC_CLUSTER_ID_1,
                ],
                OUTPUT_CLUSTERS: [
                    Identify.cluster_id,
                    Groups.cluster_id,
                    OnOff.cluster_id,
                    LevelControl.cluster_id,
                    Ota.cluster_id,
                    Color.cluster_id,
                    LightLink.cluster_id,
                ],
            }
        },
    }

    replacement = {
        ENDPOINTS: {
            1: {
                PROFILE_ID: zha.PROFILE_ID,
                DEVICE_TYPE: zha.DeviceType.COLOR_CONTROLLER,
                INPUT_CLUSTERS: [
                    Basic.cluster_id,
                    PowerConfiguration.cluster_id,
                    Identify.cluster_id,
                    LightLink.cluster_id,
                    MANUFACTURER_SPECIFIC_CLUSTER_ID_1,
                ],
                OUTPUT_CLUSTERS: [
                    Identify.cluster_id,
                    Groups.cluster_id,
                    OnOff.cluster_id,
                    LevelControl.cluster_id,
                    Ota.cluster_id,
                    Color.cluster_id,
                    LightLink.cluster_id,
                ],
            }
        },
    }
Pairing Log 
2019-12-05 19:04:50 INFO (MainThread) [zigpy.application] Device 0xf154 (cc:cc:cc:ff:fe:c6:d1:7a) joined the network
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.application] Device cc:cc:cc:ff:fe:c6:d1:7a changed id (0x7bb3 => 0xf154)
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.device] Canceling old initialize call
2019-12-05 19:04:50 INFO (MainThread) [zigpy.device] [0xf154] Requesting 'Node Descriptor'
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x09 request
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'040000060000000800000070ffc454f1ffff0901fdff040101190000'
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.zdo] [0xf154:zdo] ZDO request 0x0006: [0xfffd, 260, [25], []]
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 98 (incomingSenderEui64Handler) received: b'7ad1c6feffcccccc'
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=32774 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=1>, 11, b'\x01\x00\x00\x00\x01\x01')
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'040000130000000804000071ffc454f1ffff0c8154f17ad1c6feffcccccc80'
2019-12-05 19:04:50 INFO (MainThread) [zigpy.application] Device 0xf154 (cc:cc:cc:ff:fe:c6:d1:7a) joined the network
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.application] Skip initialization for existing device cc:cc:cc:ff:fe:c6:d1:7a
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.zdo] [0xf154:zdo] ZDO request 0x0013: [0xf154, cc:cc:cc:ff:fe:c6:d1:7a, 128]
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'006f'
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=2 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=9>, 10, b'\tT\xf1')
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0070'
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1000006800000400100006f0b0000'
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000028000004001000072ffc654f1ffff11090054f10240806811525200002c520000'
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f100000200000040010000700a0000'
2019-12-05 19:04:50 INFO (MainThread) [zigpy.device] [0xf154] Node Descriptor: <Optional byte1=2 byte2=64 mac_capability_flags=128 manufacturer_code=4456 maximum_buffer_size=82 maximum_incoming_transfer_size=82 server_mask=11264 maximum_outgoing_transfer_size=82 descriptor_capability_field=0>
2019-12-05 19:04:50 INFO (MainThread) [zigpy.device] [0xf154] Discovering endpoints
2019-12-05 19:04:50 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x0c request
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=5 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=12>, 13, b'\x0cT\xf1')
2019-12-05 19:04:50 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0071'
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000058000004001000073ffc554f1ffff060c0054f10101'
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f100000500000040010000710d0000'
2019-12-05 19:04:51 INFO (MainThread) [zigpy.device] [0xf154] Discovered endpoints: [1]
2019-12-05 19:04:51 INFO (MainThread) [zigpy.endpoint] [0xf154:1] Discovering endpoint information
2019-12-05 19:04:51 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x0e request
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=4 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=14>, 15, b'\x0eT\xf1\x01')
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0072'
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000048000004001000074ffc754f1ffff250e0054f12001040100080105000001000300001001fd070300040006000800190000030010'
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f100000400000040010000720f0000'
2019-12-05 19:04:51 INFO (MainThread) [zigpy.endpoint] [0xf154:1] Discovered endpoint information: <SimpleDescriptor endpoint=1 profile=260 device_type=2048 device_version=1 input_clusters=[0, 1, 3, 4096, 64769] output_clusters=[3, 4, 6, 8, 25, 768, 4096]>
2019-12-05 19:04:51 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x10 request
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=0 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=16>, 17, b'\x00\x10\x00\x04\x00\x05\x00')
2019-12-05 19:04:51 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0073'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000001014000000075ffc754f1ffff2318100104000042034c445305000042135a42542d4343545377697463682d4430303031'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=16 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10401000001014001000073110000'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.endpoint] [0xf154:1] Manufacturer: LDS
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.endpoint] [0xf154:1] Model: ZBT-CCTSwitch-D0001
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for LDS ZBT-CCTSwitch-D0001 (cc:cc:cc:ff:fe:c6:d1:7a)
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zigpy.quirks.smartthings.SmartthingsMultiPurposeSensor'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'bellows.zigbee.application.EZSPCoordinator'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1}
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.lutron.lzl4bwhl01remote.LutronLZL4BWHL01Remote2'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because profile_id mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.osram.a19twhite.A19TunableWhite'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {3} {1}
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.philips.rwl021.PhilipsRWL021'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2} {1}
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XbeeSensor'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1}
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.mija.smoke.MijiaHoneywellSmokeDetectorSensor'>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100030101400100008dffbf6fa0ffff1208410a0300217a540400217a540700217201'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xa06f:1:0x0300] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=65 command_id=Command.Report_Attributes>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xa06f:1:0x0300] ZCL request 0x000a: [[<Attribute attrid=3 value=<TypeValue type=uint16_t, value=21626>>, <Attribute attrid=4 value=<TypeValue type=uint16_t, value=21626>>, <Attribute attrid=7 value=<TypeValue type=uint16_t, value=370>>]]
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xa06f:1:0x0300] Attribute report received: current_x=21626, current_y=21626, color_temperature=370
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event device_registry_updated[L]: action=create, device_id=8f54115b95f547bea551c1426993a4bf>
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xf154:cc:cc:cc:ff:fe:c6:d1:7a entering async_device_initialized - is_new_join: True
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0xf154:cc:cc:cc:ff:fe:c6:d1:7a has joined the ZHA zigbee network
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): started configuration
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:ZDO](ZBT-CCTSwitch-D0001): channel: 'async_configure' stage succeeded
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x12 request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x14 request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x16 request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=18>, 19, b'\x12z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x00\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=20>, 21, b'\x14z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x01\x00\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=22>, 23, b'\x16z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x10\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0074'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0075'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0076'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401190001014000000076ffc754f1ffff0e0100010168110f03060000210100'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0019] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=CLUSTER_COMMAND manufacturer_specific=False is_reply=False disable_default_response=False> manufacturer=None tsn=0 command_id=1>
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0019] ZCL request 0x0001: [1, 4456, 783, 553648134, 1]
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000077ffc754f1ffff021200'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000074130000'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000078ffc754f1ffff021400'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000075150000'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000079ffc754f1ffff021600'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0019] OTA query_next_image handler for 'LDS ZBT-CCTSwitch-D0001': field_control=1, manufacture_id=4456, image_type=783, current_file_version=553648134, hardware_version=1
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000076170000'
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0019] No OTA image is available
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=25 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=0>, 24, b'\x19\x00\x02\x98')
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: bound 'basic' cluster: Status.SUCCESS
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: finished channel configuration
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x19 request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: bound 'power' cluster: Status.SUCCESS
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x1b request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x1000]: bound 'lightlink' cluster: Status.SUCCESS
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x1000]: finished channel configuration
2019-12-05 19:04:52 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x1000]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0077'
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x1d request
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=0 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=25>, 26, b'\x00\x19\x00\x07\x00')
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=27>, 28, b'\x00\x1b\x06\x00 \x00 \x10\x0e0*\x01')
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:52 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=29>, 30, b'\x1dz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x06\x00\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:53 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0078'
2019-12-05 19:04:53 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0079'
2019-12-05 19:04:53 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007a'
2019-12-05 19:04:53 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'04000036000000080000007affc754f1ffff0302b401'
2019-12-05 19:04:53 DEBUG (MainThread) [zigpy.zdo] [0xf154:zdo] ZDO request 0x0036: [180, <Bool.true: 1>]
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10401190001014001000077180000'
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040100000101400000007bffc754f1ffff081819010700003003'
2019-12-05 19:04:54 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=25 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f104010000010140010000781a0000'
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00040101000101400000007cffc754f1ffff04081b0700'
2019-12-05 19:04:54 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=27 command_id=Command.Configure_Reporting_rsp>
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f104010100010140010000791c0000'
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100007dffc754f1ffff021d00'
2019-12-05 19:04:54 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1000021000000400100007a1e0000'
2019-12-05 19:04:54 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: initializing channel: from_cache: False
2019-12-05 19:04:54 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:54 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: reporting 'battery_voltage' attr on 'power' cluster: 3600/10800/1: Result: '[[<ConfigureReportingResponseRecord status=Status.SUCCESS>]]'
2019-12-05 19:04:54 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x1f request
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:55 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0006]: bound 'on_off' cluster: Status.SUCCESS
2019-12-05 19:04:55 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0006]: finished channel configuration
2019-12-05 19:04:55 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0006]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:55 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x21 request
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=31>, 32, b'\x1fz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x08\x00\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=33>, 34, b'!z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x03\x03\x1fN=\x0b\x00o\r\x00\x01')
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007b'
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007c'
2019-12-05 19:04:55 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x23 request
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=35>, 36, b'\x00#\x06\x00!\x00 \x10\x0e0*\x01')
2019-12-05 19:04:55 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007d'
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100007effc754f1ffff021f00'
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1000021000000400100007b200000'
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0008]: bound 'level' cluster: Status.SUCCESS
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0008]: finished channel configuration
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0008]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100007fffc754f1ffff022100'
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1000021000000400100007c220000'
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000080ffc754f1ffff0408230700'
2019-12-05 19:04:56 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=35 command_id=Command.Configure_Reporting_rsp>
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1040101000101400100007d240000'
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0300]: bound 'light_color' cluster: Status.SUCCESS
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0300]: finished channel configuration
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0300]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: reporting 'battery_percentage_remaining' attr on 'power' cluster: 3600/10800/1: Result: '[[<ConfigureReportingResponseRecord status=Status.SUCCESS>]]'
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: finished channel configuration
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: channel: 'async_configure' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): completed configuration
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): stored in registry: ZhaDeviceEntry(name='LDS ZBT-CCTSwitch-D0001', ieee='cc:cc:cc:ff:fe:c6:d1:7a', last_seen=1575572696.4221933)
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): started initialization
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:ZDO](ZBT-CCTSwitch-D0001): entry loaded from storage: ZhaDeviceEntry(name='LDS ZBT-CCTSwitch-D0001', ieee='cc:cc:cc:ff:fe:c6:d1:7a', last_seen=1575572696.4221933)
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:ZDO](ZBT-CCTSwitch-D0001): channel: 'async_initialize' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x25 request
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:56 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x27 request
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x1000]: initializing channel: from_cache: False
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x1000]: channel: 'async_initialize' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0006]: initializing channel: from_cache: False
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0006]: channel: 'async_initialize' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0008]: initializing channel: from_cache: False
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0008]: channel: 'async_initialize' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0300]: initializing channel: from_cache: False
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0300]: channel: 'async_initialize' stage succeeded
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event device_registry_updated[L]: action=update, device_id=8f54115b95f547bea551c1426993a4bf>
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event entity_registry_updated[L]: action=create, entity_id=sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power>
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=0 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=37>, 38, b'\x00%\x00\x07\x00')
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=39>, 40, b"\x00'\x001\x00")
2019-12-05 19:04:56 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power, old_state=None, new_state=<state sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power=91; unit_of_measurement=%, friendly_name=LDS ZBT-CCTSwitch-D0001 7ad1c6fe power, device_class=battery @ 2019-12-05T14:04:56.967247-05:00>>
2019-12-05 19:04:56 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007e'
2019-12-05 19:04:57 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'007f'
2019-12-05 19:04:57 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401000001014000000081ffc754f1ffff081825010700003003'
2019-12-05 19:04:57 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=37 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:04:57 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1040100000101400100007e260000'
2019-12-05 19:04:57 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000082ffc754f1ffff081827013100003002'
2019-12-05 19:04:57 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=39 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:04:57 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: initializing channel: from_cache: False
2019-12-05 19:04:57 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0000]: channel: 'async_initialize' stage succeeded
2019-12-05 19:05:00 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00b37b000002000000400000006d086600'
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401020402010001000094feaafb36ffff0808670a0000294c06'
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=103 command_id=Command.Report_Attributes>
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=int16s, value=1612>>]]
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] Attribute report received: measured_value=1612
2019-12-05 19:05:06 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.back_porch_motion_detector_159e4606_temperature, old_state=<state sensor.back_porch_motion_detector_159e4606_temperature=16.0; unit_of_measurement=°C, friendly_name=Back Porch Motion Detector 159e4606 temperature, device_class=temperature @ 2019-12-05T13:56:57.654211-05:00>, new_state=<state sensor.back_porch_motion_detector_159e4606_temperature=16.1; unit_of_measurement=°C, friendly_name=Back Porch Motion Detector 159e4606 temperature, device_class=temperature @ 2019-12-05T14:05:06.349911-05:00>>
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000083ffc754f1ffff081827013100003002'
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=39 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.device] [0xf154] Invalid state on future for 0x27 seq -- probably duplicate response
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f1040101000101400100007f280000'
2019-12-05 19:05:06 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x29 request
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=41>, 42, b'\x00)\x00!\x00')
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0080'
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:05:06 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000084ffc754f1ffff081829012100002076'
2019-12-05 19:05:07 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=41 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f104010100010140010000802a0000'
2019-12-05 19:05:07 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x2b request
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:05:07 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power, old_state=<state sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power=91; unit_of_measurement=%, friendly_name=LDS ZBT-CCTSwitch-D0001 7ad1c6fe power, device_class=battery @ 2019-12-05T14:04:56.967247-05:00>, new_state=<state sensor.lds_zbt_cctswitch_d0001_7ad1c6fe_power=59; unit_of_measurement=%, friendly_name=LDS ZBT-CCTSwitch-D0001 7ad1c6fe power, device_class=battery @ 2019-12-05T14:05:07.425104-05:00>>
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=43>, 44, b'\x00+\x00 \x00')
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0081'
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000085ffc754f1ffff08182b01200000201d'
2019-12-05 19:05:07 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=43 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f104010100010140010000812c0000'
2019-12-05 19:05:07 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x2d request
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:05:07 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=260 clusterId=1 sourceEndpoint=1 destinationEndpoint=1 options=320 groupId=0 sequence=45>, 46, b'\x00-\x003\x00')
2019-12-05 19:05:08 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0082'
2019-12-05 19:05:08 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401010001014000000086ffc754f1ffff08182d013300002001'
2019-12-05 19:05:08 DEBUG (MainThread) [zigpy.zcl] [0xf154:1:0x0001] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=45 command_id=Command.Read_Attributes_rsp>
2019-12-05 19:05:08 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f104010100010140010000822e0000'
2019-12-05 19:05:08 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: initializing channel: from_cache: False
2019-12-05 19:05:08 DEBUG (MainThread) [homeassistant.components.zha.core.channels] [0xf154:1:0x0001]: channel: 'async_initialize' stage succeeded
2019-12-05 19:05:08 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): power source: Battery or Unknown
2019-12-05 19:05:08 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xf154](ZBT-CCTSwitch-D0001): completed initialization
Bind Attempt Log 
2019-12-05 19:17:17 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:17:17 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:17:27 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 6
2019-12-05 19:17:27 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 8
2019-12-05 19:17:27 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 768
2019-12-05 19:17:27 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x2f request
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:17:27 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x30 request
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:17:27 DEBUG (MainThread) [zigpy.device] [0xf154] Extending timeout for 0x31 request
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=47>, 50, b'/z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x06\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=48>, 51, b'0z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x08\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xf154, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=49>, 52, b'1z\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x03\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0084'
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0085'
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0086'
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:17:27 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:17:35 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:35 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:35 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:37 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:17:37 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:17:44 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:44 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:44 DEBUG (MainThread) [bellows.ezsp] Application frame 128 (incomingRouteErrorHandler) received: b'4254f1'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000000218000004001000089ffcc54f1ffff022f00'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000084320000'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100008affcc54f1ffff023000'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000085330000'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'00000021800000400100008bffcc54f1ffff023100'
2019-12-05 19:17:46 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'0054f10000210000004001000086340000'
2019-12-05 19:17:46 INFO (MainThread) [homeassistant.components.zha.api] Issue bind devices: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71]
2019-12-05 19:17:47 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:17:48 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:17:58 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:17:58 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:18:08 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:18:08 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:18:18 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:18:18 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:18:28 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:18:28 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:18:38 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:18:38 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-05 19:18:48 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-05 19:18:48 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
@Adminiuga
Copy link
Contributor

Pairing log looks normal. It bound all clusters it supposed to bind.
Bind attempt log show the request did not go through. Were you binding it to a light? Try again, but press a button on the remote just prior the binding attempt and one more time after you sent the bind request to keep device awake.

Also, in dev panel -> events subscribe to zha_event and press button on the remote. Do you get anything?

@ad1217
Copy link
Author

ad1217 commented Dec 6, 2019

I get nothing when listening to zha_event.

I was binding it to a light, and pressing buttons before and after seems to have no effect.

Bind Attempt 2 
2019-12-06 17:13:16 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 6
2019-12-06 17:13:16 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 8
2019-12-06 17:13:16 DEBUG (MainThread) [homeassistant.components.zha.api] processing binding operation for: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71] cluster: 768
2019-12-06 17:13:16 DEBUG (MainThread) [zigpy.device] [0xb9b2] Extending timeout for 0x0b request
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-06 17:13:16 DEBUG (MainThread) [zigpy.device] [0xb9b2] Extending timeout for 0x0c request
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-06 17:13:16 DEBUG (MainThread) [zigpy.device] [0xb9b2] Extending timeout for 0x0d request
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command setExtendedTimeout: (cc:cc:cc:ff:fe:c6:d1:7a, True)
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=11>, 14, b'\x0bz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x06\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=12>, 15, b'\x0cz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x08\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Application frame 126 (setExtendedTimeout) received: b''
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=13>, 16, b'\rz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x03\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0063'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0064'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'000401020402010001000099f2abfb36ffff0808130a0000293dff'
2019-12-06 17:13:17 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=False> manufacturer=None tsn=19 command_id=Command.Report_Attributes>
2019-12-06 17:13:17 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] ZCL request 0x000a: [[<Attribute attrid=0 value=<TypeValue type=int16s, value=-195>>]]
2019-12-06 17:13:17 DEBUG (MainThread) [zigpy.zcl] [0x36fb:2:0x0402] Attribute report received: measured_value=-195
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 52 (sendUnicast) received: b'0065'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b1ffbcb2b9ffff020c00'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00b2b900002100000040010000640f0000'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b2ffbcb2b9ffff020d00'
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00b2b90000210000004001000065100000'
2019-12-06 17:13:19 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b0ffbbb2b9ffff020b00'
2019-12-06 17:13:20 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:13:20 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-06 17:13:26 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b6ffbcb2b9ffff020b00'
2019-12-06 17:13:26 DEBUG (MainThread) [zigpy.device] [0xb9b2] Invalid state on future for 0x0b seq -- probably duplicate response
2019-12-06 17:13:26 DEBUG (MainThread) [bellows.ezsp] Application frame 63 (messageSentHandler) received: b'00b2b900002100000040010000630e0000'
2019-12-06 17:13:26 INFO (MainThread) [homeassistant.components.zha.api] Issue bind devices: source_ieee: [cc:cc:cc:ff:fe:c6:d1:7a] target_ieee: [cc:cc:cc:ff:fe:8d:63:71]
2019-12-06 17:13:30 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:13:36 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event timer_out_of_sync[L]: seconds=5.888581217000137>
2019-12-06 17:13:36 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-06 17:13:46 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:13:46 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-06 17:13:56 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:13:56 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-06 17:14:06 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:14:06 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''
2019-12-06 17:14:16 DEBUG (MainThread) [bellows.ezsp] Send command nop: ()
2019-12-06 17:14:16 DEBUG (MainThread) [bellows.ezsp] Application frame 5 (nop) received: b''

I tried Touch-Linking a remote to some lights as per the manual, which worked, but all the remotes controlled all of the lights that had been linked. I assume that is not how that is supposed to work, since it is not as helpful.

@Adminiuga
Copy link
Contributor

Nah, actually this time remote did report the successful bind, although it is not clearly shown in the logs:

### Bind request with 0x0b TSN
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=11>, 14, b'\x0bz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x06\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')

### Bind request with 0x0c TSN
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=12>, 15, b'\x0cz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x08\x00\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')

### Bind request with 0x0d TSN
2019-12-06 17:13:16 DEBUG (MainThread) [bellows.ezsp] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0xb9b2, <EmberApsFrame profileId=0 clusterId=33 sourceEndpoint=0 destinationEndpoint=0 options=320 groupId=0 sequence=13>, 16, b'\rz\xd1\xc6\xfe\xff\xcc\xcc\xcc\x01\x00\x03\x03qc\x8d\xfe\xff\xcc\xcc\xcc\x01')


### Reply to 0x0c TSN with success status (last two bytes)
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b1ffbcb2b9ffff020c00'

### Reply to 0x0d TSN with success status (last two bytes)
2019-12-06 17:13:17 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b2ffbcb2b9ffff020d00'

### Reply to 0x0b TSN with success status (last two bytes)
2019-12-06 17:13:19 DEBUG (MainThread) [bellows.ezsp] Application frame 69 (incomingMessageHandler) received: b'0000002180000040010000b0ffbbb2b9ffff020b00'

So it bound successfully, the question why it still doesn't work. I'd expect it to be able to control the light directly.

@dmulcahey We should collect and log results of https://github.com/home-assistant/home-assistant/blob/c9415ab75d84aedf5a1e667e40c49c3383fcbe91/homeassistant/components/zha/api.py#L560

@Adminiuga
Copy link
Contributor

i'll try to get one tomorrow and reproduce the issue. Per looks, it seems to be quite standard and should just work.

@dmulcahey
Copy link
Collaborator

I have a bunch of ideas on how to clean up binding

@Adminiuga
Copy link
Contributor

it is sending group command on ZLL group

@Adminiuga
Copy link
Contributor 

2019-12-08 19:16:17 INFO (MainThread) [zigpy.device] [0xbca5] Discovered endpoints: [1]
2019-12-08 19:16:17 INFO (MainThread) [zigpy.endpoint] [0xbca5:1] Discovering endpoint information
2019-12-08 19:16:18 INFO (MainThread) [zigpy.endpoint] [0xbca5:1] Discovered endpoint information: <SimpleDescriptor endpoint=1 profile=260 device_type=2048 device_version=1 input_clusters=[0, 1, 3, 4096, 64769] output_clusters=[3, 4, 6, 8, 25, 768, 4096]>
2019-12-08 19:16:19 DEBUG (MainThread) [zigpy.endpoint] [0xbca5:1] Manufacturer: LDS
2019-12-08 19:16:19 DEBUG (MainThread) [zigpy.endpoint] [0xbca5:1] Model: ZBT-CCTSwitch-D0001

@Adminiuga
Copy link
Contributor

On basic cluster it has attribute id 0x000B which reads www.xxxx.com 🤷‍♂

@dmulcahey
Copy link
Collaborator

On basic cluster it has attribute id 0x000B which reads www.xxxx.com 🤷‍♂

Wtf??? LOL

@Adminiuga Adminiuga mentioned this issue Dec 10, 2019
Merged
@Adminiuga
Copy link
Contributor

Apparently this device doesn't do direct binds, even though it replies with success to bind request operations.
But soon, like really soon (next year) we're going to have Device Group management, then you could add the lights to the same group as this remote and control them at once directly.

@dmulcahey
Copy link
Collaborator

My goal is to have the UI PR’s opened by the end of the weekend :)

@verjus verjus mentioned this issue Dec 17, 2019
Closed
@tube0013 tube0013 mentioned this issue Jan 10, 2020
Closed
@MattWestb MattWestb mentioned this issue Oct 1, 2021
Closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

Successfully merging a pull request may close this issue.

Add LDS ZBT-CCTSwitch-D0001 device. Adminiuga/zha-device-handlers
3 participants
@dmulcahey @ad1217 @Adminiuga