2023-04-18 22:07:09.362 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=43, profile_id=0, cluster_id=<ZDOCmd.Mgmt_Permit_Joining_req: 0x0036>, data=Serialized[b'+\xfe\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:09.364 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendBroadcast: (0xfffc, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=43), 0, 44, b'+\xfe\x00') 2023-04-18 22:07:09.368 DEBUG (MainThread) [bellows.ezsp.protocol] Send command addTransientLinkKey: (ff:ff:ff:ff:ff:ff:ff:ff, [90, 105, 103, 66, 101, 101, 65, 108, 108, 105, 97, 110, 99, 101, 48, 57]) 2023-04-18 22:07:09.371 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'67ff21a9622ae94d59947c25aa5592489c4e0cabc1cd4c75fdba5b7e' 2023-04-18 22:07:09.391 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'77ffa1a9622a15f3138c7e' 2023-04-18 22:07:09.391 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:09.395 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'70fc21a9fb2aea4da66bb5da55aac820fb0c42ceaca20be29ca800eccc4762a37e' 2023-04-18 22:07:09.396 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendBroadcast: [<EmberStatus.SUCCESS: 0>, 65] 2023-04-18 22:07:09.398 DEBUG (MainThread) [bellows.ezsp.protocol] Send command permitJoining: (254,) 2023-04-18 22:07:09.401 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'07ffb5a94d2a891e2d7e' 2023-04-18 22:07:09.402 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:09.404 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'17ffb1a9112a10b259a24a25aa5593499c0fd8abedce9874feed9d89f1e67e' 2023-04-18 22:07:09.405 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:09.407 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received stackStatusHandler: [<EmberStatus.NETWORK_OPENED: 156>] 2023-04-18 22:07:09.407 DEBUG (MainThread) [bellows.zigbee.application] Received stackStatusHandler frame with [<EmberStatus.NETWORK_OPENED: 156>] 2023-04-18 22:07:09.409 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=65), 255, 0, 0x0000, 255, 255, b'+\xfe\x00'] 2023-04-18 22:07:09.410 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=65), 255, 0, 0x0000, 255, 255, b'+\xfe\x00'] 2023-04-18 22:07:09.411 DEBUG (MainThread) [bellows.zigbee.application] Ignoring message type: <EmberIncomingMessageType.INCOMING_BROADCAST_LOOPBACK: 5> 2023-04-18 22:07:09.417 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'20fca1a9fb2a1524497e' 2023-04-18 22:07:09.417 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:09.421 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received addTransientLinkKey: [<EmberStatus.SUCCESS: 0>] 2023-04-18 22:07:09.422 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'03fd21a9762aeb82977e' 2023-04-18 22:07:09.424 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setPolicy: (<EzspPolicyId.TRUST_CENTER_POLICY: 0>, <EzspDecisionBitmask.ALLOW_UNSECURED_REJOINS|ALLOW_JOINS: 3>) 2023-04-18 22:07:09.430 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'31fda1a9762a1597f87e' 2023-04-18 22:07:09.431 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:09.433 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'148221a9012a15b15961de7e' 2023-04-18 22:07:09.435 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received permitJoining: [<EmberStatus.SUCCESS: 0>] 2023-04-18 22:07:09.442 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4282a1a9012a15161c7e' 2023-04-18 22:07:09.443 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:09.445 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setPolicy: [<EzspStatus.SUCCESS: 0>] 2023-04-18 22:07:10.368 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5282b1a96b2a134ea6944a13aa5592499d4e27eac1ce6730247e' 2023-04-18 22:07:10.369 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:10.379 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=65), 44, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:10.380 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=65), 44, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:10.381 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 44 2023-04-18 22:07:11.234 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: () 2023-04-18 22:07:11.239 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'268321a9a52a7faf7e' 2023-04-18 22:07:11.260 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6383a1a9a52a25b269946125935592499d4e23abe9ce688bf6c66e89f07e3ba7e9cddf6f8fffc6dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a2519048246fba7e' 2023-04-18 22:07:11.261 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:11.266 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[48, 48, 43, 57, 0, 1, 4, 4, 15, 11, 13, 12, 4, 2, 1, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]] 2023-04-18 22:07:11.269 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,) 2023-04-18 22:07:11.274 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'378021a9fe2a16bd4a7e' 2023-04-18 22:07:11.283 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7480a1a9fe2a15b3ae1e917e' 2023-04-18 22:07:11.284 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:11.287 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf7'] 2023-04-18 22:07:11.288 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 247 2023-04-18 22:07:11.288 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 48, MAC_TX_BROADCAST = 48, MAC_RX_UNICAST = 43, MAC_TX_UNICAST_SUCCESS = 57, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 1, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 15, APS_DATA_TX_UNICAST_SUCCESS = 11, APS_DATA_TX_UNICAST_RETRY = 13, APS_DATA_TX_UNICAST_FAILED = 12, ROUTE_DISCOVERY_INITIATED = 4, NEIGHBOR_ADDED = 2, NEIGHBOR_REMOVED = 1, NEIGHBOR_STALE = 0, JOIN_INDICATION = 1, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 247] 2023-04-18 22:07:21.292 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: () 2023-04-18 22:07:21.298 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'408121a9a52ae9d57e' 2023-04-18 22:07:21.319 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0581a1a9a52a27b268946625905592499d4e23abe9ce688bf6c66e89f07e3ba7e9cddf6f8fffc6dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a25190482493327e' 2023-04-18 22:07:21.320 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:21.326 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[50, 49, 44, 58, 0, 1, 4, 4, 15, 11, 13, 12, 4, 2, 1, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]] 2023-04-18 22:07:21.332 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,) 2023-04-18 22:07:21.337 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'518621a9fe2a1622947e' 2023-04-18 22:07:21.345 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1686a1a9fe2a15b3aea0187e' 2023-04-18 22:07:21.346 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:21.350 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf7'] 2023-04-18 22:07:21.352 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 247 2023-04-18 22:07:21.353 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 50, MAC_TX_BROADCAST = 49, MAC_RX_UNICAST = 44, MAC_TX_UNICAST_SUCCESS = 58, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 1, APS_DATA_RX_BROADCAST = 4, APS_DATA_TX_BROADCAST = 4, APS_DATA_RX_UNICAST = 15, APS_DATA_TX_UNICAST_SUCCESS = 11, APS_DATA_TX_UNICAST_RETRY = 13, APS_DATA_TX_UNICAST_FAILED = 12, ROUTE_DISCOVERY_INITIATED = 4, NEIGHBOR_ADDED = 2, NEIGHBOR_REMOVED = 1, NEIGHBOR_STALE = 0, JOIN_INDICATION = 1, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 247] 2023-04-18 22:07:23.035 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2686b1a9702a11b53ac33f7ff26d53ed9d4e27aba9ff7e' 2023-04-18 22:07:23.036 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:23.042 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received trustCenterJoinHandler: [0x0704, a4:c1:38:58:5a:75:57:63, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000] 2023-04-18 22:07:23.043 DEBUG (MainThread) [bellows.zigbee.application] Received trustCenterJoinHandler frame with [0x0704, a4:c1:38:58:5a:75:57:63, <EmberDeviceUpdate.STANDARD_SECURITY_UNSECURED_JOIN: 1>, <EmberJoinDecision.USE_PRECONFIGURED_KEY: 0>, 0x0000] 2023-04-18 22:07:23.045 INFO (MainThread) [zigpy.application] New device 0x0704 (a4:c1:38:58:5a:75:57:63) joined the network 2023-04-18 22:07:23.052 DEBUG (MainThread) [zigpy.device] [0x0704] Scheduling initialization 2023-04-18 22:07:23.055 DEBUG (MainThread) [bellows.ezsp.protocol] Send command findKeyTableEntry: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:23.057 DEBUG (MainThread) [zigpy.util] Tries remaining: 3 2023-04-18 22:07:23.058 INFO (MainThread) [zigpy.device] [0x0704] Requesting 'Node Descriptor' 2023-04-18 22:07:23.059 DEBUG (MainThread) [zigpy.util] Tries remaining: 2 2023-04-18 22:07:23.062 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x2d request 2023-04-18 22:07:23.063 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=0, source_route=None, extended_timeout=True, tsn=45, profile_id=0, cluster_id=<ZDOCmd.Node_Desc_req: 0x0002>, data=Serialized[b'-\x04\x07'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:23.065 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:23.068 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'638721a9212a76e52cce121d6bf19307397e' 2023-04-18 22:07:23.092 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3787a1a9212aea30ed7e' 2023-04-18 22:07:23.093 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:23.094 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received findKeyTableEntry: [255] 2023-04-18 22:07:23.097 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'748421a92a2a76e52cce121d6bf19366f37e' 2023-04-18 22:07:23.105 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4084a1a92a2a1c1c7e' 2023-04-18 22:07:23.106 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:23.107 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:23.109 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=45), 46, b'-\x04\x07') 2023-04-18 22:07:23.115 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'058521a9602a15b65e944a27aa5592499d4e2786c3cd4a8ffad5a47e' 2023-04-18 22:07:23.130 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5185a1a9602a15f1522a7e' 2023-04-18 22:07:23.131 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:23.133 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 67] 2023-04-18 22:07:23.630 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6185b1a9112a11b259874a25aa5592499c68fb78e9c99874f1c6678e9f294afdb3f51fcb0f2fb17e' 2023-04-18 22:07:23.630 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:23.636 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=38), 220, -45, 0x0704, 255, 255, b'\x00\x04\x07cWuZX8\xc1\xa4\x80'] 2023-04-18 22:07:23.637 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=38), 220, -45, 0x0704, 255, 255, b'\x00\x04\x07cWuZX8\xc1\xa4\x80'] 2023-04-18 22:07:23.639 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=38, profile_id=0, cluster_id=19, data=Serialized[b'\x00\x04\x07cWuZX8\xc1\xa4\x80'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=220, rssi=-45) 2023-04-18 22:07:23.642 INFO (MainThread) [zigpy.application] Device 0x0704 (a4:c1:38:58:5a:75:57:63) joined the network 2023-04-18 22:07:23.644 DEBUG (MainThread) [zigpy.device] [0x0704] Scheduling initialization 2023-04-18 22:07:23.646 DEBUG (MainThread) [zigpy.device] [0x0704] Canceling old initialize call 2023-04-18 22:07:23.647 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=False> from ep 0 to ep 0, cluster 19: b'\x00\x04\x07cWuZX8\xc1\xa4\x80' 2023-04-18 22:07:23.649 DEBUG (MainThread) [zigpy.zdo] [0x0704:zdo] ZDO request ZDOCmd.Device_annce: [0x0704, a4:c1:38:58:5a:75:57:63, 128] 2023-04-18 22:07:23.653 DEBUG (MainThread) [zigpy.util] Tries remaining: 3 2023-04-18 22:07:23.655 INFO (MainThread) [zigpy.device] [0x0704] Requesting 'Node Descriptor' 2023-04-18 22:07:23.655 DEBUG (MainThread) [zigpy.util] Tries remaining: 2 2023-04-18 22:07:23.656 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x2f request 2023-04-18 22:07:23.658 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=0, source_route=None, extended_timeout=True, tsn=47, profile_id=0, cluster_id=<ZDOCmd.Node_Desc_req: 0x0002>, data=Serialized[b'/\x04\x07'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:23.660 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:23.661 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7185b1a9772a14b35d932972df0fca715dea23d3c37e' 2023-04-18 22:07:23.661 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:23.670 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received childJoinHandler: [1, <Bool.true: 1>, 0x0704, a4:c1:38:58:5a:75:57:63, <EmberNodeType.SLEEPY_END_DEVICE: 4>] 2023-04-18 22:07:23.670 DEBUG (MainThread) [bellows.zigbee.application] Received childJoinHandler frame with [1, <Bool.true: 1>, 0x0704, a4:c1:38:58:5a:75:57:63, <EmberNodeType.SLEEPY_END_DEVICE: 4>] 2023-04-18 22:07:23.672 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'108a21a92a2a76e52cce121d6bf193aa507e' 2023-04-18 22:07:23.684 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'028aa1a92a2a32e47e' 2023-04-18 22:07:23.685 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:23.687 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:23.689 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=47), 48, b'/\x04\x07') 2023-04-18 22:07:23.694 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'218b21a9602a15b65e944a27aa5592499d4e2784ddcd488ffad03c7e' 2023-04-18 22:07:23.710 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'138ba1a9602a15f669337e' 2023-04-18 22:07:23.711 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:23.712 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 68] 2023-04-18 22:07:23.758 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'238bb1a96b2a15b65e944a27aa5592499d4e27efddce67313b7e' 2023-04-18 22:07:23.759 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:23.765 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=68), 48, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:23.767 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=68), 48, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:23.773 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'338bb1a9112a15b25996ca25aa1593499c69ff79e9c99874ece9638dfb7c7f27aadc9c2d8fffed99d5d26bd3357e' 2023-04-18 22:07:23.774 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:23.776 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=39), 216, -46, 0x0704, 255, 255, b'/\x00\x04\x07\x02@\x80A\x11BB\x00\x00*B\x00\x00'] 2023-04-18 22:07:23.778 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32770, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=39), 216, -46, 0x0704, 255, 255, b'/\x00\x04\x07\x02@\x80A\x11BB\x00\x00*B\x00\x00'] 2023-04-18 22:07:23.779 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=39, profile_id=0, cluster_id=32770, data=Serialized[b'/\x00\x04\x07\x02@\x80A\x11BB\x00\x00*B\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=216, rssi=-46) 2023-04-18 22:07:23.785 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=False> from ep 0 to ep 0, cluster 32770: b'/\x00\x04\x07\x02@\x80A\x11BB\x00\x00*B\x00\x00' 2023-04-18 22:07:23.791 INFO (MainThread) [zigpy.device] [0x0704] Got Node Descriptor: NodeDescriptor(logical_type=<LogicalType.EndDevice: 2>, complex_descriptor_available=0, user_descriptor_available=0, reserved=0, aps_flags=0, frequency_band=<FrequencyBand.Freq2400MHz: 8>, mac_capability_flags=<MACCapabilityFlags.AllocateAddress: 128>, manufacturer_code=4417, maximum_buffer_size=66, maximum_incoming_transfer_size=66, server_mask=10752, maximum_outgoing_transfer_size=66, descriptor_capability_field=<DescriptorCapability.NONE: 0>, *allocate_address=True, *is_alternate_pan_coordinator=False, *is_coordinator=False, *is_end_device=True, *is_full_function_device=False, *is_mains_powered=False, *is_receiver_on_when_idle=False, *is_router=False, *is_security_capable=False) 2023-04-18 22:07:23.794 INFO (MainThread) [zigpy.device] [0x0704] Discovering endpoints 2023-04-18 22:07:23.796 DEBUG (MainThread) [zigpy.util] Tries remaining: 3 2023-04-18 22:07:23.797 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x31 request 2023-04-18 22:07:23.798 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=0, source_route=None, extended_timeout=True, tsn=49, profile_id=0, cluster_id=<ZDOCmd.Active_EP_req: 0x0005>, data=Serialized[b'1\x04\x07'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:23.800 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:23.805 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'348821a92a2a76e52cce121d6bf1935a347e' 2023-04-18 22:07:23.817 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4488a1a92a2a91967e' 2023-04-18 22:07:23.817 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:23.820 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:23.822 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=49), 50, b'1\x04\x07') 2023-04-18 22:07:23.827 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'458921a9602a15b65e944a20aa5592499d4e279adfcd568ffa16937e' 2023-04-18 22:07:23.839 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5589a1a9602a15f76fea7e' 2023-04-18 22:07:23.840 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:23.843 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 69] 2023-04-18 22:07:23.882 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6589b1a96b2a15b65e944a20aa5592499d4e27eedfce6775d77e' 2023-04-18 22:07:23.883 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:23.890 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=69), 50, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:23.892 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=5, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=69), 50, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:23.898 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7589b1a9112a15b25991ca25aa1593499c66e366e9c99874fbf7638dfb7f3ea545707e' 2023-04-18 22:07:23.899 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:23.904 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=40), 196, -51, 0x0704, 255, 255, b'1\x00\x04\x07\x01\x01'] 2023-04-18 22:07:23.905 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32773, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=40), 196, -51, 0x0704, 255, 255, b'1\x00\x04\x07\x01\x01'] 2023-04-18 22:07:23.906 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=40, profile_id=0, cluster_id=32773, data=Serialized[b'1\x00\x04\x07\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=196, rssi=-51) 2023-04-18 22:07:23.910 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=False> from ep 0 to ep 0, cluster 32773: b'1\x00\x04\x07\x01\x01' 2023-04-18 22:07:23.914 INFO (MainThread) [zigpy.device] [0x0704] Discovered endpoints: [1] 2023-04-18 22:07:23.915 INFO (MainThread) [zigpy.device] [0x0704] Initializing endpoints [<Endpoint id=1 in=[] out=[] status=<Status.NEW: 0>>] 2023-04-18 22:07:23.916 INFO (MainThread) [zigpy.endpoint] [0x0704:1] Discovering endpoint information 2023-04-18 22:07:23.917 DEBUG (MainThread) [zigpy.util] Tries remaining: 3 2023-04-18 22:07:23.918 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x33 request 2023-04-18 22:07:23.920 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=0, source_route=None, extended_timeout=True, tsn=51, profile_id=0, cluster_id=<ZDOCmd.Simple_Desc_req: 0x0004>, data=Serialized[b'3\x04\x07\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:23.922 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:23.925 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'508e21a92a2a76e52cce121d6bf1937d5dfc7e' 2023-04-18 22:07:23.937 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'068ea1a92a2abd437e' 2023-04-18 22:07:23.938 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:23.940 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:23.941 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=51), 52, b'3\x04\x07\x01') 2023-04-18 22:07:23.948 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'618f21a9602a15b65e944a21aa5592499d4e2798d9ca548ffac753f57e' 2023-04-18 22:07:23.962 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'178fa1a9602a15f487da7e' 2023-04-18 22:07:23.963 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:23.966 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 70] 2023-04-18 22:07:24.004 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'278fb1a96b2a15b65e944a21aa5592499d4e27edd9ce6728e17e' 2023-04-18 22:07:24.005 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:24.010 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=70), 52, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.011 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=4, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=70), 52, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.021 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'378fb1a9112a15b25990ca25aa1593499c67f77be9c99874e4f5638dfb6a3ea3ea9cde6e8bfbc7ded5d2868c4621b0ec7c3ba70d677e' 2023-04-18 22:07:24.022 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:24.025 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=41), 208, -48, 0x0704, 255, 255, b'3\x00\x04\x07\x14\x01\x04\x01Q\x00\x01\x04\x04\x00\x05\x00\x00\xef\x00\x00\x02\x19\x00\n\x00'] 2023-04-18 22:07:24.026 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32772, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=41), 208, -48, 0x0704, 255, 255, b'3\x00\x04\x07\x14\x01\x04\x01Q\x00\x01\x04\x04\x00\x05\x00\x00\xef\x00\x00\x02\x19\x00\n\x00'] 2023-04-18 22:07:24.027 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=41, profile_id=0, cluster_id=32772, data=Serialized[b'3\x00\x04\x07\x14\x01\x04\x01Q\x00\x01\x04\x04\x00\x05\x00\x00\xef\x00\x00\x02\x19\x00\n\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=208, rssi=-48) 2023-04-18 22:07:24.032 DEBUG (MainThread) [zigpy.application] Received frame on uninitialized device <Device model=None manuf=None nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=False> from ep 0 to ep 0, cluster 32772: b'3\x00\x04\x07\x14\x01\x04\x01Q\x00\x01\x04\x04\x00\x05\x00\x00\xef\x00\x00\x02\x19\x00\n\x00' 2023-04-18 22:07:24.036 INFO (MainThread) [zigpy.endpoint] [0x0704:1] Discovered endpoint information: SizePrefixedSimpleDescriptor(endpoint=1, profile=260, device_type=81, device_version=1, input_clusters=[4, 5, 61184, 0], output_clusters=[25, 10]) 2023-04-18 22:07:24.038 DEBUG (MainThread) [zigpy.zcl] Unknown cluster 0xEF00 2023-04-18 22:07:24.045 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Sending request header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=<GeneralCommand.Read_Attributes: 0>, *direction=<Direction.Server_to_Client: 0>) 2023-04-18 22:07:24.049 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Sending request: Read_Attributes(attribute_ids=[4, 5]) 2023-04-18 22:07:24.051 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x35 request 2023-04-18 22:07:24.054 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=1, source_route=None, extended_timeout=True, tsn=53, profile_id=260, cluster_id=0, data=Serialized[b'\x005\x00\x04\x00\x05\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:24.056 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:24.059 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'748c21a92a2a76e52cce121d6bf1938d987e' 2023-04-18 22:07:24.069 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'408ca1a92a2a1e317e' 2023-04-18 22:07:24.070 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:24.072 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:24.075 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=53), 54, b'\x005\x00\x04\x00\x05\x00') 2023-04-18 22:07:24.082 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'058d21a9602a15b65e904b25aa5493499d4e279edbc967befdc2638cfc71f77e' 2023-04-18 22:07:24.094 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'518da1a9602a15f581037e' 2023-04-18 22:07:24.094 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:24.096 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 71] 2023-04-18 22:07:24.118 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'618db1a96b2a15b65e904b25aa5493499d4e27ecdbce6755327e' 2023-04-18 22:07:24.119 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:24.121 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=71), 54, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.122 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=71), 54, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.139 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'718db1a9112a15b658944a24ab1593499c64ff79e9c99874dede5688f87e3fe5fb928a35cacdf7eb8aa858f83f50d99d013ea5ea378415cb7c102380e2e1b27e' 2023-04-18 22:07:24.139 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:24.145 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=42), 216, -46, 0x0704, 255, 255, b'\x185\x01\x04\x00\x00B\x10_TZE200_z1tyspqw\x05\x00\x00B\x06TS0601'] 2023-04-18 22:07:24.146 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=42), 216, -46, 0x0704, 255, 255, b'\x185\x01\x04\x00\x00B\x10_TZE200_z1tyspqw\x05\x00\x00B\x06TS0601'] 2023-04-18 22:07:24.148 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=42, profile_id=260, cluster_id=0, data=Serialized[b'\x185\x01\x04\x00\x00B\x10_TZE200_z1tyspqw\x05\x00\x00B\x06TS0601'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=216, rssi=-46) 2023-04-18 22:07:24.151 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Received ZCL frame: b'\x185\x01\x04\x00\x00B\x10_TZE200_z1tyspqw\x05\x00\x00B\x06TS0601' 2023-04-18 22:07:24.154 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=53, command_id=1, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:24.161 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Decoded ZCL frame: Basic:Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0004, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='_TZE200_z1tyspqw')), ReadAttributeRecord(attrid=0x0005, status=<Status.SUCCESS: 0>, value=TypeValue(type=CharacterString, value='TS0601'))]) 2023-04-18 22:07:24.167 INFO (MainThread) [zigpy.device] [0x0704] Read model 'TS0601' and manufacturer '_TZE200_z1tyspqw' from <Endpoint id=1 in=[groups:0x0004, scenes:0x0005, None:0xEF00, basic:0x0000] out=[ota:0x0019, time:0x000A] status=<Status.ZDO_INIT: 1>> 2023-04-18 22:07:24.168 INFO (MainThread) [zigpy.device] [0x0704] Discovered basic device information for <Device model='TS0601' manuf='_TZE200_z1tyspqw' nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=True> 2023-04-18 22:07:24.171 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'018db1a96b2a134fa6944a36aa5592499c4e278d12ce6765627e' 2023-04-18 22:07:24.171 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:24.169 DEBUG (MainThread) [zigpy.application] Device is initialized <Device model='TS0601' manuf='_TZE200_z1tyspqw' nwk=0x0704 ieee=a4:c1:38:58:5a:75:57:63 is_initialized=True> 2023-04-18 22:07:24.177 DEBUG (MainThread) [zigpy.quirks.registry] Checking quirks for _TZE200_z1tyspqw TS0601 (a4:c1:38:58:5a:75:57:63) 2023-04-18 22:07:24.181 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace2'> 2023-04-18 22:07:24.182 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 242} {1} 2023-04-18 22:07:24.182 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xiaomi.aqara.opple_switch.XiaomiOpple2ButtonSwitchFace1'> 2023-04-18 22:07:24.183 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {1, 2, 41, 42, 242, 51, 21, 31} {1} 2023-04-18 22:07:24.184 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee_io.XBeeSensor'> 2023-04-18 22:07:24.186 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1} 2023-04-18 22:07:24.187 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.xbee.xbee3_io.XBee3Sensor'> 2023-04-18 22:07:24.188 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {232, 230} {1} 2023-04-18 22:07:24.188 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.tuya.ts0201.MoesTemperatureHumidtySensorWithScreen'> 2023-04-18 22:07:24.189 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint 2023-04-18 22:07:24.191 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.tag_v4.SmartThingsTagV4'> 2023-04-18 22:07:24.192 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint 2023-04-18 22:07:24.193 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.smartthings.multi.SmartthingsMultiPurposeSensor'> 2023-04-18 22:07:24.194 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint 2023-04-18 22:07:24.195 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.netvox.z308e3ed.Z308E3ED'> 2023-04-18 22:07:24.198 DEBUG (MainThread) [zigpy.quirks.registry] Fail because device_type mismatch on at least one endpoint 2023-04-18 22:07:24.199 DEBUG (MainThread) [zigpy.quirks.registry] Considering <class 'zhaquirks.gledopto.soposhgu10.SoposhGU10'> 2023-04-18 22:07:24.200 DEBUG (MainThread) [zigpy.quirks.registry] Fail because endpoint list mismatch: {11, 13} {1} 2023-04-18 22:07:24.205 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=38), 255, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.206 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65533, EmberApsFrame(profileId=0, clusterId=19, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=38), 255, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.207 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 255 2023-04-18 22:07:24.223 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'RSSISensor' using ['basic'] 2023-04-18 22:07:24.224 DEBUG (MainThread) [homeassistant.components.zha.core.discovery] 'sensor' component -> 'LQISensor' using ['basic'] 2023-04-18 22:07:24.234 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x0704:a4:c1:38:58:5a:75:57:63 entering async_device_initialized - is_new_join: True 2023-04-18 22:07:24.240 DEBUG (MainThread) [homeassistant.components.zha.core.gateway] device - 0x0704:a4:c1:38:58:5a:75:57:63 has joined the ZHA zigbee network 2023-04-18 22:07:24.242 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0704](TS0601): started configuration 2023-04-18 22:07:24.243 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:ZDO](TS0601): 'async_configure' stage succeeded 2023-04-18 22:07:24.256 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:58:5a:75:57:63, 1, 0, 4, '_TZE200_z1tyspqw') params: FOREIGN KEY constraint failed 2023-04-18 22:07:24.258 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: Configuring cluster attribute reporting 2023-04-18 22:07:24.260 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: finished channel configuration 2023-04-18 22:07:24.261 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: Performing cluster binding 2023-04-18 22:07:24.264 DEBUG (MainThread) [zigpy.device] [0x0704] Extending timeout for 0x37 request 2023-04-18 22:07:24.266 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=0, source_route=None, extended_timeout=True, tsn=55, profile_id=0, cluster_id=<ZDOCmd.Bind_req: 0x0021>, data=Serialized[b'7cWuZX8\xc1\xa4\x01\x00\xef\x03q\x86%\xfe\xff\x95\x8e\xdc\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:24.269 DEBUG (MainThread) [bellows.ezsp.protocol] Send command setExtendedTimeout: (a4:c1:38:58:5a:75:57:63, True) 2023-04-18 22:07:24.272 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0019]: finished channel configuration 2023-04-18 22:07:24.276 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'7d319221a92a2a76e52cce121d6bf19329307e' 2023-04-18 22:07:24.280 DEBUG (MainThread) [zigpy.appdb] Error handling '_save_attribute' event with (a4:c1:38:58:5a:75:57:63, 1, 0, 5, 'TS0601') params: FOREIGN KEY constraint failed 2023-04-18 22:07:24.288 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1292a1a92a2a2e177e' 2023-04-18 22:07:24.289 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:24.292 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received setExtendedTimeout: [] 2023-04-18 22:07:24.293 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=55), 56, b'7cWuZX8\xc1\xa4\x01\x00\xef\x03q\x86%\xfe\xff\x95\x8e\xdc\x01') 2023-04-18 22:07:24.302 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'229321a9602a15b65e944a04aa5592499d4e279cd5d850e8aab339d1c4bf9ba6eb22dd1e09da3924405cb58d39637e' 2023-04-18 22:07:24.316 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2393a1a9602a15fa4ab57e' 2023-04-18 22:07:24.317 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:24.320 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 72] 2023-04-18 22:07:24.485 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3393b1a96b2a15b65e944a04aa5592499d4e27e3d5ce676f047e' 2023-04-18 22:07:24.485 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:24.492 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=72), 56, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.493 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=33, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=72), 56, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:24.512 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4393b1a9112a15b259b5ca25aa1593499c65cf7de9c99874fff1638b03af7e' 2023-04-18 22:07:24.513 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:24.517 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=43), 232, -42, 0x0704, 255, 255, b'7\x00'] 2023-04-18 22:07:24.519 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=32801, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=43), 232, -42, 0x0704, 255, 255, b'7\x00'] 2023-04-18 22:07:24.522 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=43, profile_id=0, cluster_id=32801, data=Serialized[b'7\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=232, rssi=-42) 2023-04-18 22:07:24.527 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: bound 'None' cluster: Status.SUCCESS 2023-04-18 22:07:24.528 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: Configuring cluster attribute reporting 2023-04-18 22:07:24.529 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: finished channel configuration 2023-04-18 22:07:24.542 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: 'async_configure' stage succeeded 2023-04-18 22:07:24.543 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: 'async_configure' stage succeeded 2023-04-18 22:07:24.544 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0019]: 'async_configure' stage succeeded 2023-04-18 22:07:24.550 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0704](TS0601): completed configuration 2023-04-18 22:07:24.551 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0704](TS0601): started initialization 2023-04-18 22:07:24.552 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:ZDO](TS0601): 'async_initialize' stage succeeded 2023-04-18 22:07:24.560 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: initializing channel: from_cache: False 2023-04-18 22:07:24.561 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: finished channel initialization 2023-04-18 22:07:24.562 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: initializing channel: from_cache: False 2023-04-18 22:07:24.563 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: finished channel initialization 2023-04-18 22:07:24.565 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0019]: initializing channel: from_cache: False 2023-04-18 22:07:24.566 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0019]: finished channel initialization 2023-04-18 22:07:24.570 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0000]: 'async_initialize' stage succeeded 2023-04-18 22:07:24.571 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0xef00]: 'async_initialize' stage succeeded 2023-04-18 22:07:24.572 DEBUG (MainThread) [homeassistant.components.zha.core.channels.base] [0x0704:1:0x0019]: 'async_initialize' stage succeeded 2023-04-18 22:07:24.577 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0704](TS0601): power source: Battery or Unknown 2023-04-18 22:07:24.578 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x0704](TS0601): completed initialization 2023-04-18 22:07:24.593 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_z1tyspqw_ts0601_rssi 2023-04-18 22:07:24.604 INFO (MainThread) [homeassistant.helpers.entity_registry] Registered new sensor.zha entity: sensor.tze200_z1tyspqw_ts0601_lqi 2023-04-18 22:07:24.730 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5393b1a96b2a15b65e944a27aa5592499c4e27e8c3a867533e7e' 2023-04-18 22:07:24.731 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:24.738 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=67), 46, <EmberStatus.DELIVERY_FAILED: 102>, b''] 2023-04-18 22:07:24.739 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=67), 46, <EmberStatus.DELIVERY_FAILED: 102>, b''] 2023-04-18 22:07:24.740 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 46 2023-04-18 22:07:24.762 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6393b1a9112a15b259964a25aa1593499c62fb78e9c99874fec76389fec74f7e' 2023-04-18 22:07:24.763 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:24.768 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=44), 220, -45, 0x0704, 255, 255, b'\x01\x00\x00'] 2023-04-18 22:07:24.770 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=0, clusterId=2, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=44), 220, -45, 0x0704, 255, 255, b'\x01\x00\x00'] 2023-04-18 22:07:24.771 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=0, source_route=None, extended_timeout=False, tsn=44, profile_id=0, cluster_id=2, data=Serialized[b'\x01\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=220, rssi=-45) 2023-04-18 22:07:24.775 DEBUG (MainThread) [zigpy.zdo] [0x0704:zdo] ZDO request ZDOCmd.Node_Desc_req: [0x0000] 2023-04-18 22:07:24.776 DEBUG (MainThread) [zigpy.zdo] [0x0704:zdo] No handler for ZDO request:ZDOCmd.Node_Desc_req([0x0000]) 2023-04-18 22:07:24.893 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7393b1a9cf2a76e52cce121d6bf194956d7e' 2023-04-18 22:07:24.893 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:24.899 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received zigbeeKeyEstablishmentHandler: [a4:c1:38:58:5a:75:57:63, <EmberKeyStatus.TC_RESPONDED_TO_KEY_REQUEST: 6>] 2023-04-18 22:07:24.900 DEBUG (MainThread) [bellows.zigbee.application] Received zigbeeKeyEstablishmentHandler frame with [a4:c1:38:58:5a:75:57:63, <EmberKeyStatus.TC_RESPONDED_TO_KEY_REQUEST: 6>] 2023-04-18 22:07:24.938 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0393b1a9cf2a76e52cce121d6bf1a67aeb7e' 2023-04-18 22:07:24.939 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:24.943 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received zigbeeKeyEstablishmentHandler: [a4:c1:38:58:5a:75:57:63, <EmberKeyStatus.TC_REQUESTER_VERIFY_KEY_SUCCESS: 52>] 2023-04-18 22:07:24.944 DEBUG (MainThread) [bellows.zigbee.application] Received zigbeeKeyEstablishmentHandler frame with [a4:c1:38:58:5a:75:57:63, <EmberKeyStatus.TC_REQUESTER_VERIFY_KEY_SUCCESS: 52>] 2023-04-18 22:07:25.068 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1393b1a9112a11b259a24a25aa5592499c61ff79e9c99874fec4d788fe650f7e' 2023-04-18 22:07:25.069 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:25.077 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=47), 216, -46, 0x0704, 255, 255, b'\x02\xb4\x01'] 2023-04-18 22:07:25.078 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_BROADCAST: 4>, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=47), 216, -46, 0x0704, 255, 255, b'\x02\xb4\x01'] 2023-04-18 22:07:25.079 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=47, profile_id=0, cluster_id=54, data=Serialized[b'\x02\xb4\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=216, rssi=-46) 2023-04-18 22:07:25.084 DEBUG (MainThread) [zigpy.zdo] [0x0704:zdo] ZDO request ZDOCmd.Mgmt_Permit_Joining_req: [180, <Bool.true: 1>] 2023-04-18 22:07:25.559 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2393b1a96b2a134ea6944a13aa5592499c4e278412ce676bde7e' 2023-04-18 22:07:25.560 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:25.566 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=47), 255, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:25.568 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_BROADCAST: 6>, 65532, EmberApsFrame(profileId=0, clusterId=54, sourceEndpoint=0, destinationEndpoint=0, options=<EmberApsOption.APS_OPTION_NONE: 0>, groupId=0, sequence=47), 255, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:25.570 DEBUG (MainThread) [bellows.zigbee.application] Unexpected message send notification tag: 255 2023-04-18 22:07:29.688 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3393b1a9112a15b65894a524ab5593499c7ecf7de9c99874f8cf9dadf17e3dfe347e' 2023-04-18 22:07:29.689 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:29.697 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=48), 232, -42, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:29.698 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=48), 232, -42, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:29.701 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=48, profile_id=260, cluster_id=61184, data=Serialized[b'\t\xfe$\r\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=232, rssi=-42) 2023-04-18 22:07:29.704 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\xfe$\r\x00' 2023-04-18 22:07:29.708 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=254, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:29.711 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'\r\x00' 2023-04-18 22:07:29.764 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 254): b'\r\x00' 2023-04-18 22:07:29.765 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'\r\x00' 2023-04-18 22:07:30.139 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4393b1a9112a15b65894a524ab5593499c7fcf7de9c99874f8cf9dadf17e3dbfbc7e' 2023-04-18 22:07:30.140 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:30.147 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=49), 232, -42, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:30.149 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=49), 232, -42, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:30.152 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=49, profile_id=260, cluster_id=61184, data=Serialized[b'\t\xfe$\r\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=232, rssi=-42) 2023-04-18 22:07:30.155 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\xfe$\r\x00' 2023-04-18 22:07:30.158 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=254, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:30.162 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'\r\x00' 2023-04-18 22:07:30.182 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 254): b'\r\x00' 2023-04-18 22:07:30.183 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'\r\x00' 2023-04-18 22:07:30.591 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5393b1a9112a15b65894a524ab5593499c7cc37ee9c99874f8cf9dadf17e3d5aef7e' 2023-04-18 22:07:30.592 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:30.598 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=50), 228, -43, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:30.599 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=50), 228, -43, 0x0704, 255, 255, b'\t\xfe$\r\x00'] 2023-04-18 22:07:30.600 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=50, profile_id=260, cluster_id=61184, data=Serialized[b'\t\xfe$\r\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=228, rssi=-43) 2023-04-18 22:07:30.605 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\xfe$\r\x00' 2023-04-18 22:07:30.608 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=254, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:30.611 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'\r\x00' 2023-04-18 22:07:30.629 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 254): b'\r\x00' 2023-04-18 22:07:30.630 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'\r\x00' 2023-04-18 22:07:31.357 DEBUG (MainThread) [bellows.ezsp.protocol] Send command readCounters: () 2023-04-18 22:07:31.362 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'369021a9a52a8fa37e' 2023-04-18 22:07:31.384 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6490a1a9a52a23b261943725e75592499d4e21abebce7f8bedc66d89f17e3aa7e8cddc6f8fffc5dbd5d2698c4623a9ec763ba5ea758241984c2613b1e070381c0e07bbe5ca658a459a4d9e4f9ff7c3d9d46a35a251904824ea557e' 2023-04-18 22:07:31.384 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:31.389 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received readCounters: [[54, 56, 125, 77, 0, 1, 6, 6, 24, 16, 14, 13, 5, 3, 2, 0, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]] 2023-04-18 22:07:31.394 DEBUG (MainThread) [bellows.ezsp.protocol] Send command getValue: (<EzspValueId.VALUE_FREE_BUFFERS: 3>,) 2023-04-18 22:07:31.398 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'479121a9fe2a16660f7e' 2023-04-18 22:07:31.407 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7591a1a9fe2a15b3ae8fd57e' 2023-04-18 22:07:31.408 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:31.411 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received getValue: [<EzspStatus.SUCCESS: 0>, b'\xf7'] 2023-04-18 22:07:31.415 DEBUG (MainThread) [bellows.zigbee.application] Free buffers status EzspStatus.SUCCESS, value: 247 2023-04-18 22:07:31.415 DEBUG (MainThread) [bellows.zigbee.application] ezsp_counters: [MAC_RX_BROADCAST = 54, MAC_TX_BROADCAST = 56, MAC_RX_UNICAST = 125, MAC_TX_UNICAST_SUCCESS = 77, MAC_TX_UNICAST_RETRY = 0, MAC_TX_UNICAST_FAILED = 1, APS_DATA_RX_BROADCAST = 6, APS_DATA_TX_BROADCAST = 6, APS_DATA_RX_UNICAST = 24, APS_DATA_TX_UNICAST_SUCCESS = 16, APS_DATA_TX_UNICAST_RETRY = 14, APS_DATA_TX_UNICAST_FAILED = 13, ROUTE_DISCOVERY_INITIATED = 5, NEIGHBOR_ADDED = 3, NEIGHBOR_REMOVED = 2, NEIGHBOR_STALE = 0, JOIN_INDICATION = 2, CHILD_REMOVED = 0, ASH_OVERFLOW_ERROR = 0, ASH_FRAMING_ERROR = 0, ASH_OVERRUN_ERROR = 0, NWK_FRAME_COUNTER_FAILURE = 0, APS_FRAME_COUNTER_FAILURE = 0, UTILITY = 0, APS_LINK_KEY_NOT_AUTHORIZED = 0, NWK_DECRYPTION_FAILURE = 0, APS_DECRYPTION_FAILURE = 0, ALLOCATE_PACKET_BUFFER_FAILURE = 0, RELAYED_UNICAST = 0, PHY_TO_MAC_QUEUE_LIMIT_REACHED = 0, PACKET_VALIDATE_LIBRARY_DROPPED_COUNT = 0, TYPE_NWK_RETRY_OVERFLOW = 0, PHY_CCA_FAIL_COUNT = 0, BROADCAST_TABLE_FULL = 0, PTA_LO_PRI_REQUESTED = 0, PTA_HI_PRI_REQUESTED = 0, PTA_LO_PRI_DENIED = 0, PTA_HI_PRI_DENIED = 0, PTA_LO_PRI_TX_ABORTED = 0, PTA_HI_PRI_TX_ABORTED = 0, ADDRESS_CONFLICT_SENT = 0, EZSP_FREE_BUFFERS = 247] 2023-04-18 22:07:31.494 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0591b1a9112a15b65894a524ab5593499c7dc77fe9c99874fbcf9c98fc78bda5f96c7e' 2023-04-18 22:07:31.495 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:31.501 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=51), 224, -44, 0x0704, 255, 255, b'\t\xff\x11\x00\x06\x82'] 2023-04-18 22:07:31.502 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=51), 224, -44, 0x0704, 255, 255, b'\t\xff\x11\x00\x06\x82'] 2023-04-18 22:07:31.506 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=51, profile_id=260, cluster_id=61184, data=Serialized[b'\t\xff\x11\x00\x06\x82'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:31.508 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\xff\x11\x00\x06\x82' 2023-04-18 22:07:31.512 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=255, command_id=17, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:31.516 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 17 b'\x00\x06\x82' 2023-04-18 22:07:31.535 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x11 (TSN 255): b'\x00\x06\x82' 2023-04-18 22:07:31.536 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x11: b'\x00\x06\x82' 2023-04-18 22:07:32.634 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1591b1a9112a15b658944a24ab5593499c7ac77fe9c99874f2ce6383fd7e1fef0932fe576b00e7dad757b67e' 2023-04-18 22:07:32.635 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:32.641 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=52), 224, -44, 0x0704, 255, 255, b'\x08\x00\n\x01\x00 H\xe2\xff 8\xe4\xff \x01'] 2023-04-18 22:07:32.642 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=52), 224, -44, 0x0704, 255, 255, b'\x08\x00\n\x01\x00 H\xe2\xff 8\xe4\xff \x01'] 2023-04-18 22:07:32.644 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=52, profile_id=260, cluster_id=0, data=Serialized[b'\x08\x00\n\x01\x00 H\xe2\xff 8\xe4\xff \x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:32.647 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Received ZCL frame: b'\x08\x00\n\x01\x00 H\xe2\xff 8\xe4\xff \x01' 2023-04-18 22:07:32.649 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=0, command_id=10, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:32.654 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Decoded ZCL frame: Basic:Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=72)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=56)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=1))]) 2023-04-18 22:07:32.660 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Received command 0x0A (TSN 0): Report_Attributes(attribute_reports=[Attribute(attrid=0x0001, value=TypeValue(type=uint8_t, value=72)), Attribute(attrid=0xFFE2, value=TypeValue(type=uint8_t, value=56)), Attribute(attrid=0xFFE4, value=TypeValue(type=uint8_t, value=1))]) 2023-04-18 22:07:32.664 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Attribute report received: app_version=72, 0xFFE2=56, 0xFFE4=1 2023-04-18 22:07:32.668 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=0, command_id=<GeneralCommand.Default_Response: 11>, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:32.672 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x0000] Sending reply: Default_Response(command_id=10, status=<Status.SUCCESS: 0>) 2023-04-18 22:07:32.674 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=1, source_route=None, extended_timeout=False, tsn=0, profile_id=260, cluster_id=0, data=Serialized[b'\x18\x00\x0b\n\x00'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:32.676 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=0), 57, b'\x18\x00\x0b\n\x00') 2023-04-18 22:07:32.684 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'529621a9602a15b65e904b25aa5493099d4e27abd4cb7f8bf6cc63eb5b7e' 2023-04-18 22:07:32.697 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'2696a1a9602a15fe3b287e' 2023-04-18 22:07:32.698 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'83401b7e' 2023-04-18 22:07:32.700 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 76] 2023-04-18 22:07:32.739 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'3696b1a96b2a15b65e904b25aa5493099d4e27e7d4ce671eff7e' 2023-04-18 22:07:32.740 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8430fc7e' 2023-04-18 22:07:32.744 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=76), 57, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:32.746 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=76), 57, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:32.757 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'4696b1a9112a15b6589e4a24ab5593499c7bc37ee9c99874f8c66289fb7e3db92a7e' 2023-04-18 22:07:32.757 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8520dd7e' 2023-04-18 22:07:32.761 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=53), 228, -43, 0x0704, 255, 255, b'\x00\x01\x00\x07\x00'] 2023-04-18 22:07:32.762 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=53), 228, -43, 0x0704, 255, 255, b'\x00\x01\x00\x07\x00'] 2023-04-18 22:07:32.763 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=53, profile_id=260, cluster_id=10, data=Serialized[b'\x00\x01\x00\x07\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=228, rssi=-43) 2023-04-18 22:07:32.766 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Received ZCL frame: b'\x00\x01\x00\x07\x00' 2023-04-18 22:07:32.769 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=False, *is_general=True), tsn=1, command_id=0, *direction=<Direction.Server_to_Client: 0>) 2023-04-18 22:07:32.773 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Decoded ZCL frame: Time:Read_Attributes(attribute_ids=[7]) 2023-04-18 22:07:32.775 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Received command 0x00 (TSN 1): Read_Attributes(attribute_ids=[7]) 2023-04-18 22:07:32.782 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Sending reply header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.GLOBAL_COMMAND: 0>, is_manufacturer_specific=False, direction=<Direction.Client_to_Server: 1>, disable_default_response=1, reserved=0, *is_cluster=False, *is_general=True), tsn=1, command_id=<GeneralCommand.Read_Attributes_rsp: 1>, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:32.788 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0x000a] Sending reply: Read_Attributes_rsp(status_records=[ReadAttributeRecord(attrid=0x0007, status=<Status.SUCCESS: 0>, value=TypeValue(type=LocalTime, value=735170852))]) 2023-04-18 22:07:32.791 DEBUG (MainThread) [bellows.zigbee.application] Sending packet ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), dst_ep=1, source_route=None, extended_timeout=False, tsn=1, profile_id=260, cluster_id=10, data=Serialized[b'\x18\x01\x01\x07\x00\x00#$\xd1\xd1+'], tx_options=<TransmitOptions.ACK: 1>, radius=0, non_member_radius=0, lqi=None, rssi=None) 2023-04-18 22:07:32.793 DEBUG (MainThread) [bellows.ezsp.protocol] Send command sendUnicast: (<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 0x0704, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=1), 58, b'\x18\x01\x01\x07\x00\x00#$\xd1\xd1+') 2023-04-18 22:07:32.798 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'659721a9602a15b65e904b2faa5493099d4e27aad7c57f8afcc16389df5aee76c0780c7e' 2023-04-18 22:07:32.813 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'5797a1a9602a15ff64b77e' 2023-04-18 22:07:32.814 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8610be7e' 2023-04-18 22:07:32.816 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received sendUnicast: [<EmberStatus.SUCCESS: 0>, 77] 2023-04-18 22:07:32.866 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'6797b1a96b2a15b65e904b2faa5493099d4e27e6d7ce67d5247e' 2023-04-18 22:07:32.867 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'87009f7e' 2023-04-18 22:07:32.874 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received messageSentHandler: [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=77), 58, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:32.876 DEBUG (MainThread) [bellows.zigbee.application] Received messageSentHandler frame with [<EmberOutgoingMessageType.OUTGOING_DIRECT: 0>, 1796, EmberApsFrame(profileId=260, clusterId=10, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=77), 58, <EmberStatus.SUCCESS: 0>, b''] 2023-04-18 22:07:32.883 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'7797b1a9112a15b65894a524ab5593499c78c77fe9c99874f7cf618bfc713ea3ebccdf6d6f407e' 2023-04-18 22:07:32.884 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8070787e' 2023-04-18 22:07:32.887 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=54), 224, -44, 0x0704, 255, 255, b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'] 2023-04-18 22:07:32.888 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=54), 224, -44, 0x0704, 255, 255, b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'] 2023-04-18 22:07:32.889 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=54, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:32.891 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:32.894 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=2, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:32.898 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:32.924 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 2): b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:32.925 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:33.884 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'0797b1a9112a15b65894a524ab5593499c79c77fe9c99874f7cf618bfc713ea3ebccdf6d8c827e' 2023-04-18 22:07:33.885 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'8160597e' 2023-04-18 22:07:33.890 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=55), 224, -44, 0x0704, 255, 255, b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'] 2023-04-18 22:07:33.892 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=55), 224, -44, 0x0704, 255, 255, b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'] 2023-04-18 22:07:33.893 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=55, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:33.896 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x02\x02\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:33.899 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=2, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:33.903 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:33.922 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 2): b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:33.924 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x0f\x01\x04\x00\x01\x01' 2023-04-18 22:07:35.895 DEBUG (bellows.thread_0) [bellows.uart] Data frame: b'1797b1a9112a15b65894a524ab5593499c76c77fe9c99874f0cf608bfc6e3da5ebc9de6f8f69c54ffd7e' 2023-04-18 22:07:35.896 DEBUG (bellows.thread_0) [bellows.uart] Sending: b'82503a7e' 2023-04-18 22:07:35.902 DEBUG (MainThread) [bellows.ezsp.protocol] Application frame received incomingMessageHandler: [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=56), 224, -44, 0x0704, 255, 255, b'\t\x03\x02\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96'] 2023-04-18 22:07:35.903 DEBUG (MainThread) [bellows.zigbee.application] Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=61184, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY: 256>, groupId=0, sequence=56), 224, -44, 0x0704, 255, 255, b'\t\x03\x02\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96'] 2023-04-18 22:07:35.905 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=56, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x03\x02\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:35.908 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x03\x02\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96' 2023-04-18 22:07:35.910 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=3, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:35.916 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96' 2023-04-18 22:07:35.932 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 3): b'\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96' 2023-04-18 22:07:35.933 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x10\x02\x02\x00\x04\x00\x00\x00\x96' 2023-04-18 22:07:37.907 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=57, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x04\x02\x00\x11\x03\x02\x00\x04\x00\x00\x00\xd4'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:37.909 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x04\x02\x00\x11\x03\x02\x00\x04\x00\x00\x00\xd4' 2023-04-18 22:07:37.915 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=4, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:37.917 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x11\x03\x02\x00\x04\x00\x00\x00\xd4' 2023-04-18 22:07:37.926 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 4): b'\x00\x11\x03\x02\x00\x04\x00\x00\x00\xd4' 2023-04-18 22:07:37.927 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x11\x03\x02\x00\x04\x00\x00\x00\xd4' 2023-04-18 22:07:39.916 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=58, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x05\x02\x00\x12\x04\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:39.918 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x05\x02\x00\x12\x04\x01\x00\x01\x00' 2023-04-18 22:07:39.923 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=5, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:39.926 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x12\x04\x01\x00\x01\x00' 2023-04-18 22:07:39.934 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 5): b'\x00\x12\x04\x01\x00\x01\x00' 2023-04-18 22:07:39.935 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x12\x04\x01\x00\x01\x00' 2023-04-18 22:07:40.968 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF611](CLA60 RGBW Z3): Device seen - marking the device available and resetting counter 2023-04-18 22:07:40.969 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0xF611](CLA60 RGBW Z3): Update device availability -  device available: True - new availability: True - changed: False 2023-04-18 22:07:41.926 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=59, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x06\x02\x00\x13\x06\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:41.928 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x06\x02\x00\x13\x06\x04\x00\x01\x00' 2023-04-18 22:07:41.934 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=6, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:41.936 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x13\x06\x04\x00\x01\x00' 2023-04-18 22:07:41.946 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 6): b'\x00\x13\x06\x04\x00\x01\x00' 2023-04-18 22:07:41.947 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x13\x06\x04\x00\x01\x00' 2023-04-18 22:07:43.935 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=60, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x07\x02\x00\x14\x07\x04\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:43.937 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x07\x02\x00\x14\x07\x04\x00\x01\x00' 2023-04-18 22:07:43.943 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=7, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:43.945 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x14\x07\x04\x00\x01\x00' 2023-04-18 22:07:43.955 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 7): b'\x00\x14\x07\x04\x00\x01\x00' 2023-04-18 22:07:43.956 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x14\x07\x04\x00\x01\x00' 2023-04-18 22:07:45.944 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=61, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x08\x02\x00\x15\x08\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:45.946 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x08\x02\x00\x15\x08\x01\x00\x01\x00' 2023-04-18 22:07:45.952 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=8, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:45.954 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x15\x08\x01\x00\x01\x00' 2023-04-18 22:07:45.964 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 8): b'\x00\x15\x08\x01\x00\x01\x00' 2023-04-18 22:07:45.965 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x15\x08\x01\x00\x01\x00' 2023-04-18 22:07:47.955 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=62, profile_id=260, cluster_id=61184, data=Serialized[b'\t\t\x02\x00\x16\x0c\x01\x00\x01\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:47.960 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\t\x02\x00\x16\x0c\x01\x00\x01\x00' 2023-04-18 22:07:47.963 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=9, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:47.966 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x16\x0c\x01\x00\x01\x00' 2023-04-18 22:07:47.977 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 9): b'\x00\x16\x0c\x01\x00\x01\x00' 2023-04-18 22:07:47.977 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x16\x0c\x01\x00\x01\x00' 2023-04-18 22:07:49.964 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=63, profile_id=260, cluster_id=61184, data=Serialized[b'\t\n\x02\x00\x17\r\x02\x00\x04\x00\x00\x00d'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=228, rssi=-43) 2023-04-18 22:07:49.966 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\n\x02\x00\x17\r\x02\x00\x04\x00\x00\x00d' 2023-04-18 22:07:49.971 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=10, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:49.974 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x17\r\x02\x00\x04\x00\x00\x00d' 2023-04-18 22:07:49.984 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 10): b'\x00\x17\r\x02\x00\x04\x00\x00\x00d' 2023-04-18 22:07:49.984 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x17\r\x02\x00\x04\x00\x00\x00d' 2023-04-18 22:07:51.972 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=64, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x0b\x02\x00\x18\x0f\x02\x00\x04\x00\x00\x002'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:51.974 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x0b\x02\x00\x18\x0f\x02\x00\x04\x00\x00\x002' 2023-04-18 22:07:51.979 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=11, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:51.981 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x18\x0f\x02\x00\x04\x00\x00\x002' 2023-04-18 22:07:51.992 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 11): b'\x00\x18\x0f\x02\x00\x04\x00\x00\x002' 2023-04-18 22:07:51.993 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x18\x0f\x02\x00\x04\x00\x00\x002' 2023-04-18 22:07:53.982 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=65, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x0c\x02\x00\x19\x10\x02\x00\x04\x00\x00\x01,'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:53.984 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x0c\x02\x00\x19\x10\x02\x00\x04\x00\x00\x01,' 2023-04-18 22:07:53.989 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=12, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:53.992 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00\x19\x10\x02\x00\x04\x00\x00\x01,' 2023-04-18 22:07:54.002 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 12): b'\x00\x19\x10\x02\x00\x04\x00\x00\x01,' 2023-04-18 22:07:54.003 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00\x19\x10\x02\x00\x04\x00\x00\x01,' 2023-04-18 22:07:55.943 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x57B8](TH01): Device seen - marking the device available and resetting counter 2023-04-18 22:07:55.944 DEBUG (MainThread) [homeassistant.components.zha.core.device] [0x57B8](TH01): Update device availability -  device available: True - new availability: True - changed: False 2023-04-18 22:07:55.994 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=66, profile_id=260, cluster_id=61184, data=Serialized[b'\t\r\x02\x00&\x11\x00\x00\x11\x01\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:55.996 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\r\x02\x00&\x11\x00\x00\x11\x01\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96' 2023-04-18 22:07:56.001 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=13, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:56.003 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b'\x00&\x11\x00\x00\x11\x01\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96' 2023-04-18 22:07:56.014 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 13): b'\x00&\x11\x00\x00\x11\x01\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96' 2023-04-18 22:07:56.014 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b'\x00&\x11\x00\x00\x11\x01\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96' 2023-04-18 22:07:58.002 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=67, profile_id=260, cluster_id=61184, data=Serialized[b"\t\x0e\x02\x00'\x12\x00\x00\x11\x02\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96"], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:07:58.005 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b"\t\x0e\x02\x00'\x12\x00\x00\x11\x02\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96" 2023-04-18 22:07:58.010 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=14, command_id=2, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:07:58.012 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 2 b"\x00'\x12\x00\x00\x11\x02\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96" 2023-04-18 22:07:58.023 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x02 (TSN 14): b"\x00'\x12\x00\x00\x11\x02\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96" 2023-04-18 22:07:58.023 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x02: b"\x00'\x12\x00\x00\x11\x02\x06\x00\x00\xc8\x0c\x00\x00\x96\x12\x00\x00\xdc\x16\x00\x00\x96" 2023-04-18 22:08:00.008 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=68, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x0f$-\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:00.010 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x0f$-\x00' 2023-04-18 22:08:00.016 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=15, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:00.018 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'-\x00' 2023-04-18 22:08:00.026 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 15): b'-\x00' 2023-04-18 22:08:00.026 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'-\x00' 2023-04-18 22:08:02.010 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=69, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x10$.\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:02.012 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x10$.\x00' 2023-04-18 22:08:02.017 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=16, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:02.019 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'.\x00' 2023-04-18 22:08:02.030 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 16): b'.\x00' 2023-04-18 22:08:02.030 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'.\x00' 2023-04-18 22:08:04.012 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=70, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x11$/\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:04.014 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x11$/\x00' 2023-04-18 22:08:04.020 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=17, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:04.022 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'/\x00' 2023-04-18 22:08:04.031 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 17): b'/\x00' 2023-04-18 22:08:04.032 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'/\x00' 2023-04-18 22:08:06.017 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=71, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x12$0\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=228, rssi=-43) 2023-04-18 22:08:06.019 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x12$0\x00' 2023-04-18 22:08:06.024 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=18, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:06.028 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'0\x00' 2023-04-18 22:08:06.037 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 18): b'0\x00' 2023-04-18 22:08:06.038 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'0\x00' 2023-04-18 22:08:08.019 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=72, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x13$1\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:08.021 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x13$1\x00' 2023-04-18 22:08:08.026 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=19, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:08.028 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'1\x00' 2023-04-18 22:08:08.038 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 19): b'1\x00' 2023-04-18 22:08:08.039 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'1\x00' 2023-04-18 22:08:10.024 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=73, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x14$2\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=228, rssi=-43) 2023-04-18 22:08:10.026 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x14$2\x00' 2023-04-18 22:08:10.031 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=20, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:10.033 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'2\x00' 2023-04-18 22:08:10.043 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 20): b'2\x00' 2023-04-18 22:08:10.044 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'2\x00' 2023-04-18 22:08:12.027 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=74, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x15$3\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:12.029 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x15$3\x00' 2023-04-18 22:08:12.035 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=21, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:12.038 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'3\x00' 2023-04-18 22:08:12.047 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 21): b'3\x00' 2023-04-18 22:08:12.048 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'3\x00' 2023-04-18 22:08:14.030 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=75, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x16$4\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:14.032 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x16$4\x00' 2023-04-18 22:08:14.037 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=22, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:14.039 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'4\x00' 2023-04-18 22:08:14.044 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 22): b'4\x00' 2023-04-18 22:08:14.045 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'4\x00' 2023-04-18 22:08:16.032 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=76, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x17$5\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:16.034 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x17$5\x00' 2023-04-18 22:08:16.039 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=23, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:16.041 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'5\x00' 2023-04-18 22:08:16.052 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 23): b'5\x00' 2023-04-18 22:08:16.052 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'5\x00' 2023-04-18 22:08:18.035 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=77, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x18$6\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:18.037 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x18$6\x00' 2023-04-18 22:08:18.041 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=24, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:18.043 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'6\x00' 2023-04-18 22:08:18.055 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 24): b'6\x00' 2023-04-18 22:08:18.055 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'6\x00' 2023-04-18 22:08:20.038 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=78, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x19$7\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:20.040 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x19$7\x00' 2023-04-18 22:08:20.045 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=25, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:20.048 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'7\x00' 2023-04-18 22:08:20.058 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 25): b'7\x00' 2023-04-18 22:08:20.059 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'7\x00' 2023-04-18 22:08:22.041 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=79, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x1a$8\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:22.043 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x1a$8\x00' 2023-04-18 22:08:22.049 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=26, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:22.054 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'8\x00' 2023-04-18 22:08:22.062 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 26): b'8\x00' 2023-04-18 22:08:22.063 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'8\x00' 2023-04-18 22:08:23.857 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF611), src_ep=0, dst=AddrModeAddress(addr_mode=<AddrMode.Broadcast: 15>, address=<BroadcastAddress.ALL_ROUTERS_AND_COORDINATOR: 65532>), dst_ep=0, source_route=None, extended_timeout=False, tsn=165, profile_id=0, cluster_id=6, data=Serialized[b'\x01\xfd\xff\x04\x01\x01\x19\x00\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=216, rssi=-46) 2023-04-18 22:08:23.860 DEBUG (MainThread) [zigpy.zdo] [0xf611:zdo] ZDO request ZDOCmd.Match_Desc_req: [0xFFFD, 260, [25], []] 2023-04-18 22:08:24.044 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0704), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=80, profile_id=260, cluster_id=61184, data=Serialized[b'\t\x1b$9\x00'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=224, rssi=-44) 2023-04-18 22:08:24.046 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received ZCL frame: b'\t\x1b$9\x00' 2023-04-18 22:08:24.051 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Client_to_Server: 1>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=27, command_id=36, *direction=<Direction.Client_to_Server: 1>) 2023-04-18 22:08:24.053 WARNING (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Unknown cluster command 36 b'9\x00' 2023-04-18 22:08:24.063 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] Received command 0x24 (TSN 27): b'9\x00' 2023-04-18 22:08:24.063 DEBUG (MainThread) [zigpy.zcl] [0x0704:1:0xef00] No explicit handler for cluster command 0x24: b'9\x00' 2023-04-18 22:08:25.914 DEBUG (MainThread) [zigpy.application] Received a packet: ZigbeePacket(src=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0xF611), src_ep=1, dst=AddrModeAddress(addr_mode=<AddrMode.NWK: 2>, address=0x0000), dst_ep=1, source_route=None, extended_timeout=False, tsn=166, profile_id=260, cluster_id=25, data=Serialized[b'\x01\x08\x01\x01\x89\x11\x11\x00\x011\x10\x00\xe3\r'], tx_options=<TransmitOptions.NONE: 0>, radius=0, non_member_radius=0, lqi=216, rssi=-46) 2023-04-18 22:08:25.916 DEBUG (MainThread) [zigpy.zcl] [0xF611:1:0x0019] Received ZCL frame: b'\x01\x08\x01\x01\x89\x11\x11\x00\x011\x10\x00\xe3\r' 2023-04-18 22:08:25.922 DEBUG (MainThread) [zigpy.zcl] [0xF611:1:0x0019] Decoded ZCL frame header: ZCLHeader(frame_control=FrameControl(frame_type=<FrameType.CLUSTER_COMMAND: 1>, is_manufacturer_specific=0, direction=<Direction.Server_to_Client: 0>, disable_default_response=0, reserved=0, *is_cluster=True, *is_general=False), tsn=8, command_id=1, *direction=<Direction.Server_to_Client: 0>) 2023-04-18 22:08:25.925 DEBUG (MainThread) [zigpy.zcl] [0xF611:1:0x0019] Decoded ZCL frame: Ota:QueryNextImageCommand(field_control=<FieldControl.HardwareVersion: 1>, manufacturer_code=4489, image_type=17, current_file_version=1061121, hardware_version=3555) 2023-04-18 22:08:25.927 DEBUG (MainThread) [zigpy.zcl] [0xF611:1:0x0019] Received command 0x01 (TSN 8): QueryNextImageCommand(field_control=<FieldControl.HardwareVersion: 1>, manufacturer_code=4489, image_type=17, current_file_version=1061121, hardware_version=3555) 2023-04-18 22:08:25.931 DEBUG (MainThread) [zigpy.zcl] [0xF611:1:0x0019] OTA query_next_image handler for 'LEDVANCE CLA60 RGBW Z3': field_control=FieldControl.HardwareVersion, manufacturer_id=4489, image_type=17, current_file_version=1061121, hardware_version=3555, model='CLA60 RGBW Z3' 2023-04-18 22:08:25.932 DEBUG (MainThread) [zigpy.zcl