IKEA STYRBAR remote unexpectedly controls unrelated night light (Third Reality 3RSNL02043Z)

[andzno1]

Hi everyone,

I’m encountering a very strange issue and I’m not sure where to start troubleshooting.

I’m running Home Assistant Core 2025.4.1 on a Raspberry Pi 4, using a ZBT-1 coordinator with zigbee2mqtt for Zigbee support.

I have paired the Third Reality 3RSNL02043Z night light, which works flawlessly when controlled via Home Assistant.

Later, I added an IKEA STYRBAR remote control.

Here’s the issue: right after pairing, with no automation or binding set up, the STYRBAR starts controlling the night light directly:

• Pressing the Light large (top) button turns the night light on
• Pressing the Light small (bottom) button turns it off

Home Assistant does not register these state changes at all.

Even after adding automations for the remote to control other lights (which work as expected), the STYRBAR continues to control the night light, which is completely unrelated and should not be affected.

I’m not sure where this behavior originates from. Can anyone point me in the right direction?

[andzno1]

Here are the zigbee2mqtt debug logs from the moment I enabled pairing until after I toggled the night light (device ID 0xf44250c3b2f10000) a few times using the newly paired remote (device ID 0x84ba20fffe624d65):

Part 1

[2025-04-30 00:27:34] debug: 	z2m:mqtt: Received MQTT message on 'zigbee2mqtt/bridge/request/permit_join' with data '{"device":null,"time":254,"transaction":"t4off-1"}'
[2025-04-30 00:27:34] info: 	z2m: Zigbee: allowing new devices to join.
[2025-04-30 00:27:34] debug: 	zh:ember:ezsp: ===> [FRAME: ID=273:"IMPORT_TRANSIENT_KEY" Seq=133 Len=30]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=7](ackRx=5)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=6)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [FRAME: ID=273:"IMPORT_TRANSIENT_KEY" Seq=133 Len=9]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ===> [FRAME: ID=85:"SET_POLICY" Seq=134 Len=7]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=0](ackRx=6)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=7)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [FRAME: ID=85:"SET_POLICY" Seq=134 Len=6]
[2025-04-30 00:27:35] debug: 	zh:ember: [EzspPolicyId] SET 'TRUST_CENTER_POLICY' TO '3' with status=OK.
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ===> [FRAME: ID=34:"PERMIT_JOINING" Seq=135 Len=6]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=1](ackRx=7)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=0)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=0)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [FRAME: ID=34:"PERMIT_JOINING" Seq=135 Len=6]
[2025-04-30 00:27:35] debug: 	zh:ember: Permit joining on coordinator for 254 sec.
[2025-04-30 00:27:35] debug: 	zh:ember: ~~~> [ZDO PERMIT_JOINING_REQUEST BROADCAST to=65532 messageTag=96 payload=60fe01]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ===> [FRAME: ID=54:"SEND_BROADCAST" Seq=136 Len=24]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=3](ackRx=0)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=25:"STACK_STATUS_HANDLER" Seq=135 Len=6]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ezspStackStatusHandler: status=ZIGBEE_NETWORK_OPENED
[2025-04-30 00:27:35] info: 	zh:ember: [STACK STATUS] Network opened.
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=1)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [FRAME: ID=54:"SEND_BROADCAST" Seq=136 Len=7]
[2025-04-30 00:27:35] debug: 	zh:ember: ~~~> [SENT ZDO BROADCAST messageTag=96 apsSequence=66 status=OK]
[2025-04-30 00:27:35] debug: 	zh:ember: ~~~> [ZCL BROADCAST apsFrame={"profileId":41440,"clusterId":33,"sourceEndpoint":242,"destinationEndpoint":242,"options":4416,"groupId":65533,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":1,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":121,"commandIdentifier":2}]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ===> [FRAME: ID=54:"SEND_BROADCAST" Seq=137 Len=27]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=4](ackRx=1)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=2)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [FRAME: ID=54:"SEND_BROADCAST" Seq=137 Len=7]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ~~~> [SENT type=BROADCAST apsSequence=67 messageTag=94 status=OK]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:27:35] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=2)
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=137 Len=30]
[2025-04-30 00:27:35] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=BROADCAST_LOOPBACK apsFrame={"profileId":41440,"clusterId":33,"sourceEndpoint":242,"destinationEndpoint":242,"options":256,"groupId":0,"sequence":67} packetInfo:{"senderShortId":0,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":255,"lastHopRssi":0,"lastHopTimestamp":0} messageContents=1979020bfe00
[2025-04-30 00:27:35] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/permit_join', payload '{"data":{"time":254},"status":"ok","transaction":"t4off-1"}'
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=2)
[2025-04-30 00:27:36] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=137 Len=22]
[2025-04-30 00:27:36] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65532 apsFrame={"profileId":0,"clusterId":54,"sourceEndpoint":0,"destinationEndpoint":0,"options":256,"groupId":0,"sequence":66} messageTag=96
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:27:36] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=2)
[2025-04-30 00:27:36] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=137 Len=22]
[2025-04-30 00:27:36] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65533 apsFrame={"profileId":41440,"clusterId":33,"sourceEndpoint":242,"destinationEndpoint":242,"options":256,"groupId":0,"sequence":67} messageTag=94
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=2)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=137 Len=20]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=49466 sourceEui=0xa4c138c5bc6fdceb lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=137 Len=43]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":2820,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":182} packetInfo:{"senderShortId":49466,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=08e50a050521e30008052100000b05290000
[2025-04-30 00:27:42] debug: 	zh:controller: Received payload: clusterID=2820, address=49466, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=64, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":229,"commandIdentifier":10},"payload":[{"attrId":1285,"dataType":33,"attrData":227},{"attrId":1288,"dataType":33,"attrData":0},{"attrId":1291,"dataType":41,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-04-30 00:27:42] debug: 	zh:controller:endpoint: ZCL command 0xa4c138c5bc6fdceb/1 haElectricalMeasurement.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":229,"writeUndiv":false})
[2025-04-30 00:27:42] debug: 	zh:ember: ~~~> [ZCL to=0xa4c138c5bc6fdceb:49466 apsFrame={"profileId":260,"clusterId":2820,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":229,"commandIdentifier":11}]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=138 Len=26]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=2](ackRx=2)
[2025-04-30 00:27:42] debug: 	z2m: Received Zigbee message from '*** redacted ***', type 'attributeReport', cluster 'haElectricalMeasurement', data '{"activePower":0,"rmsCurrent":0,"rmsVoltage":227}' from endpoint 1 with groupID 0
[2025-04-30 00:27:42] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/*** redacted ***', payload '{"child_lock":"UNLOCK","countdown":0,"current":0,"energy":0.06,"indicator_mode":"off","linkquality":64,"power":0,"power_outage_memory":"restore","state":"OFF","update":{"installed_version":-1,"latest_version":-1,"state":null},"voltage":227}'
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=138 Len=7]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=68 messageTag=95 status=OK]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=138 Len=22]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=49466 apsFrame={"profileId":260,"clusterId":2820,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":68} messageTag=95
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=3)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=138 Len=37]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":1794,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":183} packetInfo:{"senderShortId":49466,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":68,"lastHopRssi":-94,"lastHopTimestamp":0} messageContents=08e60a000025060000000000
[2025-04-30 00:27:42] debug: 	zh:controller: Received payload: clusterID=1794, address=49466, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=68, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":230,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":37,"attrData":6}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-04-30 00:27:42] debug: 	zh:controller:endpoint: ZCL command 0xa4c138c5bc6fdceb/1 seMetering.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":230,"writeUndiv":false})
[2025-04-30 00:27:42] debug: 	zh:ember: ~~~> [ZCL to=0xa4c138c5bc6fdceb:49466 apsFrame={"profileId":260,"clusterId":1794,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":230,"commandIdentifier":11}]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=139 Len=26]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=5](ackRx=3)
[2025-04-30 00:27:42] debug: 	z2m: Received Zigbee message from '*** redacted ***', type 'attributeReport', cluster 'seMetering', data '{"currentSummDelivered":6}' from endpoint 1 with groupID 0
[2025-04-30 00:27:42] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/*** redacted ***', payload '{"child_lock":"UNLOCK","countdown":0,"current":0,"energy":0.06,"indicator_mode":"off","linkquality":68,"power":0,"power_outage_memory":"restore","state":"OFF","update":{"installed_version":-1,"latest_version":-1,"state":null},"voltage":227}'
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=4)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=139 Len=7]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=69 messageTag=96 status=OK]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:27:42] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=4)
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=139 Len=22]
[2025-04-30 00:27:42] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=49466 apsFrame={"profileId":260,"clusterId":1794,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":69} messageTag=96
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=4)
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=139 Len=40]
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":0,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":184} packetInfo:{"senderShortId":49466,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":68,"lastHopRssi":-94,"lastHopTimestamp":0} messageContents=08e70a01002050e2ff2038e4ff2000
[2025-04-30 00:27:48] debug: 	zh:controller: Received payload: clusterID=0, address=49466, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=68, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":231,"commandIdentifier":10},"payload":[{"attrId":1,"dataType":32,"attrData":80},{"attrId":65506,"dataType":32,"attrData":56},{"attrId":65508,"dataType":32,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2025-04-30 00:27:48] debug: 	zh:controller:endpoint: ZCL command 0xa4c138c5bc6fdceb/1 genBasic.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":231,"writeUndiv":false})
[2025-04-30 00:27:48] debug: 	zh:ember: ~~~> [ZCL to=0xa4c138c5bc6fdceb:49466 apsFrame={"profileId":260,"clusterId":0,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":231,"commandIdentifier":11}]
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=140 Len=26]
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=0](ackRx=4)
[2025-04-30 00:27:48] debug: 	z2m: Received Zigbee message from '*** redacted ***', type 'attributeReport', cluster 'genBasic', data '{"65506":56,"65508":0,"appVersion":80}' from endpoint 1 with groupID 0
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=5)
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=140 Len=7]
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=70 messageTag=97 status=OK]
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:27:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=5)
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=140 Len=22]
[2025-04-30 00:27:48] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=49466 apsFrame={"profileId":260,"clusterId":0,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":70} messageTag=97
[2025-04-30 00:27:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:27:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:27:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:27:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=5)
[2025-04-30 00:27:52] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=36:"TRUST_CENTER_JOIN_HANDLER" Seq=140 Len=19]
[2025-04-30 00:27:52] debug: 	zh:ember:ezsp: ezspTrustCenterJoinHandler: newNodeId=28524 newNodeEui64=0x84ba20fffe624d65 status=DEVICE_LEFT policyDecision=NO_ACTION parentOfNewNodeId=49466
[2025-04-30 00:27:52] debug: 	zh:controller: Device leave '0x84ba20fffe624d65'
[2025-04-30 00:27:52] debug: 	zh:controller: Device leave is from unknown or already deleted device '0x84ba20fffe624d65'
[2025-04-30 00:27:53] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/*** redacted ***', payload '{"child_lock":"UNLOCK","countdown":0,"current":0,"energy":0.06,"indicator_mode":"off","linkquality":68,"power":0,"power_outage_memory":"restore","state":"OFF","update":{"installed_version":-1,"latest_version":-1,"state":null},"voltage":227}'
[2025-04-30 00:27:53] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/*** redacted ***', payload '{"child_lock":"UNLOCK","countdown":0,"current":0,"energy":0.06,"indicator_mode":"off","linkquality":68,"power":0,"power_outage_memory":"restore","state":"OFF","update":{"installed_version":-1,"latest_version":-1,"state":null},"voltage":227}'
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=5)
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=140 Len=18]
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=68 lastHopRssi=-94 relayCount=0 relayList=
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=5)
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=36:"TRUST_CENTER_JOIN_HANDLER" Seq=140 Len=19]
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: ezspTrustCenterJoinHandler: newNodeId=2413 newNodeEui64=0x84ba20fffe624d65 status=STANDARD_SECURITY_UNSECURED_JOIN policyDecision=USE_PRECONFIGURED_KEY parentOfNewNodeId=703
[2025-04-30 00:28:04] debug: 	zh:controller: Device '0x84ba20fffe624d65' joined
[2025-04-30 00:28:04] debug: 	zh:controller: Device '0x84ba20fffe624d65' accepted by handler
[2025-04-30 00:28:04] debug: 	zh:controller: Deleted device '0x84ba20fffe624d65' joined, undeleting
[2025-04-30 00:28:04] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:04] debug: 	zh:controller: Device '0x84ba20fffe624d65' is already in database with different network address, updating network address
[2025-04-30 00:28:04] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:04] info: 	zh:controller: Interview for '0x84ba20fffe624d65' started
[2025-04-30 00:28:04] debug: 	zh:controller:device: Interview - start device '0x84ba20fffe624d65'
[2025-04-30 00:28:04] debug: 	zh:controller:device: Interview - skip node descriptor request for '0x84ba20fffe624d65', already got it
[2025-04-30 00:28:04] debug: 	zh:ember: ~~~> [ZDO ACTIVE_ENDPOINTS_REQUEST UNICAST to=0x84ba20fffe624d65:2413 messageTag=97 payload=616d09]
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=141 Len=24]
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=5](ackRx=5)
[2025-04-30 00:28:04] info: 	z2m: Device '0x84ba20fffe624d65' joined
[2025-04-30 00:28:04] info: 	z2m: Starting interview of '0x84ba20fffe624d65'
[2025-04-30 00:28:04] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65"},"type":"device_joined"}'
[2025-04-30 00:28:04] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65","status":"started"},"type":"device_interview"}'
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:04] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=6)
[2025-04-30 00:28:04] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=141 Len=7]
[2025-04-30 00:28:04] debug: 	zh:ember: ~~~> [SENT ZDO UNICAST messageTag=97 apsSequence=73 status=OK]
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=6 frmTx=6)
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=6)
[2025-04-30 00:28:09] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=141 Len=22]
[2025-04-30 00:28:09] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=ZIGBEE_DELIVERY_FAILED type=DIRECT indexOrDestination=2413 apsFrame={"profileId":0,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":73} messageTag=97
[2025-04-30 00:28:09] debug: 	zh:ember: ~x~> DELIVERY_FAILED [indexOrDestination=2413 apsFrame={"profileId":0,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":73} messageTag=97]
[2025-04-30 00:28:09] debug: 	zh:controller:device: Interview - active endpoints request failed for '0x84ba20fffe624d65', attempt 1 (Error: Delivery failed for '2413'.)
[2025-04-30 00:28:09] debug: 	zh:ember: ~~~> [ZDO ACTIVE_ENDPOINTS_REQUEST UNICAST to=0x84ba20fffe624d65:2413 messageTag=98 payload=626d09]
[2025-04-30 00:28:09] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=142 Len=24]
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=7](ackRx=6)
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:09] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=7)
[2025-04-30 00:28:09] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=142 Len=7]
[2025-04-30 00:28:09] debug: 	zh:ember: ~~~> [SENT ZDO UNICAST messageTag=98 apsSequence=74 status=OK]
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=142 Len=18]
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=64 lastHopRssi=-95 relayCount=0 relayList=
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:13] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=7)
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=196:"INCOMING_NETWORK_STATUS_HANDLER" Seq=142 Len=8]
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: ezspIncomingNetworkStatusHandler: errorCode=ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY target=2413
[2025-04-30 00:28:13] info: 	zh:ember:ezsp: Received network/route error ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY for "2413".
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=128:"INCOMING_ROUTE_ERROR_HANDLER" Seq=142 Len=8]
[2025-04-30 00:28:13] debug: 	zh:ember:ezsp: ezspIncomingRouteErrorHandler: status=MAC_UNKNOWN_HEADER_TYPE target=2413
[2025-04-30 00:28:14] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:14] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:14] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:14] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=7)

[andzno1]

Logs part 2:

[2025-04-30 00:28:14] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=142 Len=22]
[2025-04-30 00:28:14] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=ZIGBEE_DELIVERY_FAILED type=DIRECT indexOrDestination=2413 apsFrame={"profileId":0,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":74} messageTag=98
[2025-04-30 00:28:14] debug: 	zh:ember: ~x~> DELIVERY_FAILED [indexOrDestination=2413 apsFrame={"profileId":0,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":74} messageTag=98]
[2025-04-30 00:28:14] debug: 	zh:controller:device: Interview - active endpoints request failed for '0x84ba20fffe624d65', attempt 2 (Error: Delivery failed for '2413'.)
[2025-04-30 00:28:14] debug: 	zh:controller:device: Interview - quirks check for 'Remote Control N2'-'IKEA of Sweden'-'EndDevice'
[2025-04-30 00:28:14] debug: 	zh:controller:device: Interview - quirks did not match
[2025-04-30 00:28:14] debug: 	zh:controller:device: Interview - failed for device '0x84ba20fffe624d65' with error 'Error: Interview failed because can not get active endpoints ('0x84ba20fffe624d65')'
[2025-04-30 00:28:14] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:14] error: 	zh:controller: Interview failed for '0x84ba20fffe624d65 with error 'Error: Interview failed because can not get active endpoints ('0x84ba20fffe624d65')'
[2025-04-30 00:28:14] error: 	z2m: Failed to interview '0x84ba20fffe624d65', device has not successfully been paired
[2025-04-30 00:28:14] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/button/0x84ba20fffe624d65/identify/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state","value_template":"{{ value_json.state }}"}],"command_topic":"zigbee2mqtt/0x84ba20fffe624d65/set/identify","device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"device_class":"identify","entity_category":"config","object_id":"0x84ba20fffe624d65_identify","origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"payload_press":"identify","unique_id":"0x84ba20fffe624d65_identify_zigbee2mqtt"}'
[2025-04-30 00:28:14] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65","status":"failed"},"type":"device_interview"}'
[2025-04-30 00:28:14] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x84ba20fffe624d65/battery/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state","value_template":"{{ value_json.state }}"}],"device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"device_class":"battery","enabled_by_default":true,"entity_category":"diagnostic","object_id":"0x84ba20fffe624d65_battery","origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"state_class":"measurement","state_topic":"zigbee2mqtt/0x84ba20fffe624d65","unique_id":"0x84ba20fffe624d65_battery_zigbee2mqtt","unit_of_measurement":"%","value_template":"{{ value_json.battery }}"}'
[2025-04-30 00:28:14] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/sensor/0x84ba20fffe624d65/linkquality/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state","value_template":"{{ value_json.state }}"}],"device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"enabled_by_default":false,"entity_category":"diagnostic","icon":"mdi:signal","name":"Linkquality","object_id":"0x84ba20fffe624d65_linkquality","origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"state_class":"measurement","state_topic":"zigbee2mqtt/0x84ba20fffe624d65","unique_id":"0x84ba20fffe624d65_linkquality_zigbee2mqtt","unit_of_measurement":"lqi","value_template":"{{ value_json.linkquality }}"}'
[2025-04-30 00:28:14] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/update/0x84ba20fffe624d65/update/config', payload '{"availability":[{"topic":"zigbee2mqtt/bridge/state","value_template":"{{ value_json.state }}"}],"command_topic":"zigbee2mqtt/bridge/request/device/ota_update/update","device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"device_class":"firmware","entity_category":"config","entity_picture":"https://github.com/Koenkk/zigbee2mqtt/raw/master/images/logo.png","name":null,"object_id":"0x84ba20fffe624d65","origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"payload_install":"{\"id\": \"0x84ba20fffe624d65\"}","state_topic":"zigbee2mqtt/0x84ba20fffe624d65","unique_id":"0x84ba20fffe624d65_update_zigbee2mqtt","value_template":"{\"latest_version\":\"{{ value_json['update']['latest_version'] }}\",\"installed_version\":\"{{ value_json['update']['installed_version'] }}\",\"update_percentage\":{{ value_json['update'].get('progress', 'null') }},\"in_progress\":{{ (value_json['update']['state'] == 'updating')|lower }}}"}'
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=142 Len=18]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=60 lastHopRssi=-96 relayCount=0 relayList=
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=142 Len=39]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":25,"sourceEndpoint":1,"destinationEndpoint":1,"options":320,"groupId":0,"sequence":254} packetInfo:{"senderShortId":703,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":72,"lastHopRssi":-93,"lastHopTimestamp":0} messageContents=015001010d130000520000000000
[2025-04-30 00:28:15] debug: 	zh:controller: Received payload: clusterID=25, address=703, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=72, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":80,"commandIdentifier":1},"payload":{"fieldControl":1,"manufacturerCode":4877,"imageType":0,"fileVersion":82},"command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}}
[2025-04-30 00:28:15] debug: 	z2m: Received Zigbee message from 'night light', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":1,"fileVersion":82,"imageType":0,"manufacturerCode":4877}' from endpoint 1 with groupID 0
[2025-04-30 00:28:15] debug: 	z2m: Device 'night light' requested OTA
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=142 Len=18]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=56 lastHopRssi=-97 relayCount=0 relayList=
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=196:"INCOMING_NETWORK_STATUS_HANDLER" Seq=142 Len=8]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingNetworkStatusHandler: errorCode=ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY target=2413
[2025-04-30 00:28:15] info: 	zh:ember:ezsp: Received network/route error ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY for "2413".
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=128:"INCOMING_ROUTE_ERROR_HANDLER" Seq=142 Len=8]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingRouteErrorHandler: status=MAC_UNKNOWN_HEADER_TYPE target=2413
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=142 Len=18]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=64 lastHopRssi=-95 relayCount=0 relayList=
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=7)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=36:"TRUST_CENTER_JOIN_HANDLER" Seq=142 Len=19]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ezspTrustCenterJoinHandler: newNodeId=16313 newNodeEui64=0x84ba20fffe624d65 status=STANDARD_SECURITY_UNSECURED_JOIN policyDecision=USE_PRECONFIGURED_KEY parentOfNewNodeId=703
[2025-04-30 00:28:15] debug: 	zh:controller: Device '0x84ba20fffe624d65' joined
[2025-04-30 00:28:15] debug: 	zh:controller: Device '0x84ba20fffe624d65' accepted by handler
[2025-04-30 00:28:15] debug: 	zh:controller: Device '0x84ba20fffe624d65' is already in database with different network address, updating network address
[2025-04-30 00:28:15] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:15] info: 	zh:controller: Interview for '0x84ba20fffe624d65' started
[2025-04-30 00:28:15] debug: 	zh:controller:device: Interview - start device '0x84ba20fffe624d65'
[2025-04-30 00:28:15] debug: 	zh:controller:device: Interview - skip node descriptor request for '0x84ba20fffe624d65', already got it
[2025-04-30 00:28:15] debug: 	zh:ember: ~~~> [ZDO ACTIVE_ENDPOINTS_REQUEST UNICAST to=0x84ba20fffe624d65:16313 messageTag=99 payload=63b93f]
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=143 Len=24]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=3](ackRx=7)
[2025-04-30 00:28:15] info: 	z2m: Starting interview of '0x84ba20fffe624d65'
[2025-04-30 00:28:15] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65","status":"started"},"type":"device_interview"}'
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:15] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=0)
[2025-04-30 00:28:15] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=143 Len=7]
[2025-04-30 00:28:15] debug: 	zh:ember: ~~~> [SENT ZDO UNICAST messageTag=99 apsSequence=77 status=OK]
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=18]
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=60 lastHopRssi=-96 relayCount=0 relayList=
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:16] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=0)
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=196:"INCOMING_NETWORK_STATUS_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: ezspIncomingNetworkStatusHandler: errorCode=ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY target=2413
[2025-04-30 00:28:16] info: 	zh:ember:ezsp: Received network/route error ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY for "2413".
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=128:"INCOMING_ROUTE_ERROR_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:16] debug: 	zh:ember:ezsp: ezspIncomingRouteErrorHandler: status=MAC_UNKNOWN_HEADER_TYPE target=2413
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=18]
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=64 lastHopRssi=-95 relayCount=0 relayList=
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:18] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=0)
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=196:"INCOMING_NETWORK_STATUS_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: ezspIncomingNetworkStatusHandler: errorCode=ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY target=2413
[2025-04-30 00:28:18] info: 	zh:ember:ezsp: Received network/route error ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY for "2413".
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=128:"INCOMING_ROUTE_ERROR_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:18] debug: 	zh:ember:ezsp: ezspIncomingRouteErrorHandler: status=MAC_UNKNOWN_HEADER_TYPE target=2413
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=0)
[2025-04-30 00:28:19] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=18]
[2025-04-30 00:28:19] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=56 lastHopRssi=-97 relayCount=0 relayList=
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:19] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=0)
[2025-04-30 00:28:19] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=36:"TRUST_CENTER_JOIN_HANDLER" Seq=143 Len=19]
[2025-04-30 00:28:19] debug: 	zh:ember:ezsp: ezspTrustCenterJoinHandler: newNodeId=16313 newNodeEui64=0x84ba20fffe624d65 status=STANDARD_SECURITY_UNSECURED_JOIN policyDecision=USE_PRECONFIGURED_KEY parentOfNewNodeId=703
[2025-04-30 00:28:19] debug: 	zh:controller: Device '0x84ba20fffe624d65' joined
[2025-04-30 00:28:19] debug: 	zh:controller: Device '0x84ba20fffe624d65' accepted by handler
[2025-04-30 00:28:19] debug: 	zh:controller: Not interviewing '0x84ba20fffe624d65', completed 'false', in progress 'true'
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=18]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=703 sourceEui=0xf44250c3b2f10000 lastHopLqi=64 lastHopRssi=-95 relayCount=0 relayList=
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=196:"INCOMING_NETWORK_STATUS_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: ezspIncomingNetworkStatusHandler: errorCode=ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY target=2413
[2025-04-30 00:28:20] info: 	zh:ember:ezsp: Received network/route error ROUTE_ERROR_INDIRECT_TRANSACTION_EXPIRY for "2413".
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=128:"INCOMING_ROUTE_ERROR_HANDLER" Seq=143 Len=8]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: ezspIncomingRouteErrorHandler: status=MAC_UNKNOWN_HEADER_TYPE target=2413
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=98:"undefined" Seq=143 Len=13]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=x= Ignored unused/unknown [CBFRAME: ID=98:"undefined" Seq=143 Len=13]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=143 Len=37]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=BROADCAST apsFrame={"profileId":0,"clusterId":19,"sourceEndpoint":0,"destinationEndpoint":0,"options":1024,"groupId":0,"sequence":171} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":68,"lastHopRssi":-94,"lastHopTimestamp":0} messageContents=81b93f654d62feff20ba8480
[2025-04-30 00:28:20] debug: 	zh:controller: Received ZDO response: clusterId=END_DEVICE_ANNOUNCE, status=SUCCESS, payload={"nwkAddress":16313,"eui64":"0x84ba20fffe624d65","capabilities":{"alternatePANCoordinator":0,"deviceType":0,"powerSource":0,"rxOnWhenIdle":0,"reserved1":0,"reserved2":0,"securityCapability":0,"allocateAddress":1}}
[2025-04-30 00:28:20] debug: 	zh:controller: Device announce from '0x84ba20fffe624d65:16313'
[2025-04-30 00:28:20] debug: 	z2m: Device '0x84ba20fffe624d65' announced itself
[2025-04-30 00:28:20] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65"},"type":"device_announce"}'
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:20] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=0)
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=143 Len=22]
[2025-04-30 00:28:20] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65533 apsFrame={"profileId":0,"clusterId":19,"sourceEndpoint":0,"destinationEndpoint":0,"options":1024,"groupId":0,"sequence":171} messageTag=255
[2025-04-30 00:28:21] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:21] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:21] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:21] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=0)
[2025-04-30 00:28:21] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:21] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:22] debug: 	z2m: Retrieving state of '0x84ba20fffe624d65' after reconnect
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=64 lastHopRssi=-95 relayCount=1 relayList=703
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=155:"ZIGBEE_KEY_ESTABLISHMENT_HANDLER" Seq=143 Len=14]
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: ezspZigbeeKeyEstablishmentHandler: partner=0x84ba20fffe624d65 status=TC_RESPONDED_TO_KEY_REQUEST
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:22] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=0)
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:22] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=72 lastHopRssi=-93 relayCount=1 relayList=703
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=0)
[2025-04-30 00:28:23] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:23] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:23] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=0)
[2025-04-30 00:28:23] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=155:"ZIGBEE_KEY_ESTABLISHMENT_HANDLER" Seq=143 Len=14]
[2025-04-30 00:28:23] debug: 	zh:ember:ezsp: ezspZigbeeKeyEstablishmentHandler: partner=0x84ba20fffe624d65 status=TC_REQUESTER_VERIFY_KEY_SUCCESS
[2025-04-30 00:28:24] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:24] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:24] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:24] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=0)
[2025-04-30 00:28:24] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:24] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=143 Len=31]
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":0,"clusterId":32773,"sourceEndpoint":0,"destinationEndpoint":0,"options":320,"groupId":0,"sequence":175} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=6300b93f0101
[2025-04-30 00:28:25] debug: 	zh:controller: Received ZDO response: clusterId=ACTIVE_ENDPOINTS_RESPONSE, status=SUCCESS, payload={"nwkAddress":16313,"endpointList":[1]}
[2025-04-30 00:28:25] debug: 	zh:controller:device: Interview - got active endpoints for device '0x84ba20fffe624d65'

[andzno1]

Logs part 3:

[2025-04-30 00:28:25] debug: 	zh:ember: ~~~> [ZDO SIMPLE_DESCRIPTOR_REQUEST UNICAST to=0x84ba20fffe624d65:16313 messageTag=100 payload=64b93f01]
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=144 Len=25]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=0 frmRx=3](ackRx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=1)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=143 Len=20]
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=1)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=0)
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=143 Len=22]
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":0,"clusterId":5,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":77} messageTag=99
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=0 frmTx=1)
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:25] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=1)
[2025-04-30 00:28:25] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=144 Len=7]
[2025-04-30 00:28:25] debug: 	zh:ember: ~~~> [SENT ZDO UNICAST messageTag=100 apsSequence=83 status=OK]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=1 frmTx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=144 Len=20]
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=1 frmTx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=144 Len=60]
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":0,"clusterId":32772,"sourceEndpoint":0,"destinationEndpoint":0,"options":320,"groupId":0,"sequence":176} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":60,"lastHopRssi":-96,"lastHopTimestamp":0} messageContents=6400b93f1e010401200801060000010003002000001057fc0503000600080019000010
[2025-04-30 00:28:26] debug: 	zh:controller: Received ZDO response: clusterId=SIMPLE_DESCRIPTOR_RESPONSE, status=SUCCESS, payload={"nwkAddress":16313,"length":30,"endpoint":1,"profileId":260,"deviceId":2080,"deviceVersion":1,"inClusterList":[0,1,3,32,4096,64599],"outClusterList":[3,6,8,25,4096]}
[2025-04-30 00:28:26] debug: 	zh:controller:device: Interview - got simple descriptor for endpoint '1' device '0x84ba20fffe624d65'
[2025-04-30 00:28:26] debug: 	zh:controller:device: Interview - Poll control - binding '0x84ba20fffe624d65' endpoint '1'
[2025-04-30 00:28:26] debug: 	zh:controller:endpoint: Bind 0x84ba20fffe624d65/1 genPollCtrl from '0x44e2f8fffe8c9ca2/1'
[2025-04-30 00:28:26] debug: 	zh:ember: ~~~> [ZDO BIND_REQUEST UNICAST to=0x84ba20fffe624d65:16313 messageTag=101 payload=65654d62feff20ba8401200003a29c8cfefff8e24401]
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=145 Len=43]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=1 frmRx=0](ackRx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1](ackRx=1 frmTx=2)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=1 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=1)
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=144 Len=20]
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=64 lastHopRssi=-95 relayCount=1 relayList=703
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=1 frmTx=2)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=2)
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=145 Len=7]
[2025-04-30 00:28:26] debug: 	zh:ember: ~~~> [SENT ZDO UNICAST messageTag=101 apsSequence=84 status=OK]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:26] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=2)
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=145 Len=22]
[2025-04-30 00:28:26] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":0,"clusterId":4,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":83} messageTag=100
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=145 Len=28]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=BROADCAST apsFrame={"profileId":260,"clusterId":3,"sourceEndpoint":1,"destinationEndpoint":255,"options":256,"groupId":0,"sequence":178} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=010101
[2025-04-30 00:28:27] debug: 	zh:controller: Received payload: clusterID=3, address=16313, groupID=0, endpoint=1, destinationEndpoint=255, wasBroadcast=true, linkQuality=64, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":1,"commandIdentifier":1},"payload":{},"command":{"ID":1,"parameters":[],"name":"identifyQuery"}}
[2025-04-30 00:28:27] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandIdentifyQuery', cluster 'genIdentify', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:27] debug: 	z2m: Skipping message, still interviewing
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=145 Len=20]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=145 Len=27]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":0,"clusterId":32801,"sourceEndpoint":0,"destinationEndpoint":0,"options":320,"groupId":0,"sequence":179} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":72,"lastHopRssi":-93,"lastHopTimestamp":0} messageContents=6500
[2025-04-30 00:28:27] debug: 	zh:controller: Received ZDO response: clusterId=BIND_RESPONSE, status=SUCCESS, payload=undefined
[2025-04-30 00:28:27] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:27] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genPollCtrl.read(["checkinInterval"], {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":0,"reservedBits":0,"writeUndiv":false,"sendPolicy":"immediate"})
[2025-04-30 00:28:27] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send read request immediately (sendPolicy=immediate)
[2025-04-30 00:28:27] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":32,"sourceEndpoint":1,"destinationEndpoint":1,"options":4416,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":0,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":122,"commandIdentifier":0}]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=146 Len=26]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=2 frmRx=6](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=145 Len=20]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=145 Len=22]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":0,"clusterId":33,"sourceEndpoint":0,"destinationEndpoint":0,"options":4416,"groupId":0,"sequence":84} messageTag=101
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2](ackRx=2 frmTx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=2 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=2)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=145 Len=22]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65535 apsFrame={"profileId":260,"clusterId":3,"sourceEndpoint":1,"destinationEndpoint":255,"options":256,"groupId":0,"sequence":178} messageTag=255
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=2 frmTx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=146 Len=7]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=85 messageTag=98 status=OK]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:27] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=146 Len=22]
[2025-04-30 00:28:27] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65532 apsFrame={"profileId":0,"clusterId":54,"sourceEndpoint":0,"destinationEndpoint":0,"options":0,"groupId":0,"sequence":177} messageTag=255
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=146 Len=20]
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=146 Len=36]
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":32,"sourceEndpoint":1,"destinationEndpoint":1,"options":320,"groupId":0,"sequence":180} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":68,"lastHopRssi":-94,"lastHopTimestamp":0} messageContents=187a010000002300a30200
[2025-04-30 00:28:28] debug: 	zh:controller: Received payload: clusterID=32, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=68, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":122,"commandIdentifier":1},"payload":[{"attrId":0,"status":0,"dataType":35,"attrData":172800}],"command":{"ID":1,"name":"readRsp","parameters":[{"name":"attrId","type":33},{"name":"status","type":32},{"name":"dataType","type":32,"conditions":[{"type":"statusEquals","value":0}]},{"name":"attrData","type":1000,"conditions":[{"type":"statusEquals","value":0}]}]}}
[2025-04-30 00:28:28] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'readResponse', cluster 'genPollCtrl', data '{"checkinInterval":172800}' from endpoint 1 with groupID 0
[2025-04-30 00:28:28] debug: 	z2m: Skipping message, still interviewing
[2025-04-30 00:28:28] debug: 	zh:controller:device: Interview - completed for device '0x84ba20fffe624d65'
[2025-04-30 00:28:28] debug: 	zh:controller:database: Writing database to '/config/zigbee2mqtt/database.db'
[2025-04-30 00:28:28] info: 	zh:controller: Succesfully interviewed '0x84ba20fffe624d65'
[2025-04-30 00:28:28] info: 	z2m: Successfully interviewed '0x84ba20fffe624d65', device has successfully been paired
[2025-04-30 00:28:28] info: 	z2m: Device '0x84ba20fffe624d65' is supported, identified as: IKEA STYRBAR remote control (E2001/E2002)
[2025-04-30 00:28:28] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/event', payload '{"data":{"definition":{"description":"STYRBAR remote control","exposes":[{"access":2,"category":"config","description":"Initiate device identification. This device is asleep by default.You may need to wake it up first before sending the identify command.","label":"Identify","name":"identify","property":"identify","type":"enum","values":["identify"]},{"access":5,"category":"diagnostic","description":"Remaining battery in %","label":"Battery","name":"battery","property":"battery","type":"numeric","unit":"%","value_max":100,"value_min":0},{"access":1,"category":"diagnostic","description":"Triggered action (e.g. a button click)","label":"Action","name":"action","property":"action","type":"enum","values":["on","off","brightness_move_up","brightness_move_down","brightness_stop","arrow_left_click","arrow_left_hold","arrow_left_release","arrow_right_click","arrow_right_hold","arrow_right_release"]},{"access":1,"category":"diagnostic","description":"Link quality (signal strength)","label":"Linkquality","name":"linkquality","property":"linkquality","type":"numeric","unit":"lqi","value_max":255,"value_min":0}],"model":"E2001/E2002","options":[{"access":2,"description":"Sets the duration of the identification procedure in seconds (i.e., how long the device would flash).The value ranges from 1 to 30 seconds (default: 3).","label":"Identify timeout","name":"identify_timeout","property":"identify_timeout","type":"numeric","value_max":30,"value_min":1},{"access":2,"description":"Simulate a brightness value. If this device provides a brightness_move_up or brightness_move_down action it is possible to specify the update interval and delta. The action_brightness_delta indicates the delta for each interval.","features":[{"access":2,"description":"Delta per interval, 20 by default","label":"Delta","name":"delta","property":"delta","type":"numeric","value_min":0},{"access":2,"description":"Interval duration","label":"Interval","name":"interval","property":"interval","type":"numeric","unit":"ms","value_min":0}],"label":"Simulated brightness","name":"simulated_brightness","property":"simulated_brightness","type":"composite"}],"supports_ota":true,"vendor":"IKEA"},"friendly_name":"0x84ba20fffe624d65","ieee_address":"0x84ba20fffe624d65","status":"successful","supported":true},"type":"device_interview"}'
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:28] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=3)
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=146 Len=20]
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=146 Len=22]
[2025-04-30 00:28:28] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":32,"sourceEndpoint":1,"destinationEndpoint":1,"options":4416,"groupId":0,"sequence":85} messageTag=98
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=3)
[2025-04-30 00:28:47] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=146 Len=22]
[2025-04-30 00:28:47] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=BROADCAST indexOrDestination=65533 apsFrame={"profileId":0,"clusterId":0,"sourceEndpoint":0,"destinationEndpoint":0,"options":1024,"groupId":0,"sequence":184} messageTag=255
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:47] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=3)
[2025-04-30 00:28:47] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=146 Len=20]
[2025-04-30 00:28:47] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=3)
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=146 Len=20]
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=64 lastHopRssi=-95 relayCount=1 relayList=703
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3](ackRx=3 frmTx=3)
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=3 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=3)
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=146 Len=28]
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":189} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=010300
[2025-04-30 00:28:48] debug: 	zh:controller: Received payload: clusterID=6, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=64, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":3,"commandIdentifier":0},"payload":{},"command":{"ID":0,"parameters":[],"name":"off"}}
[2025-04-30 00:28:48] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genOnOff.defaultRsp({"cmdId":0,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":3,"writeUndiv":false})
[2025-04-30 00:28:48] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send defaultRsp request immediately (sendPolicy=undefined)
[2025-04-30 00:28:48] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":3,"commandIdentifier":11}]
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=147 Len=26]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=3 frmRx=3](ackRx=3)
[2025-04-30 00:28:48] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandOff', cluster 'genOnOff', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=3 frmTx=4)
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=4)
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=147 Len=7]
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=87 messageTag=99 status=OK]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:48] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=4)
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=147 Len=22]
[2025-04-30 00:28:48] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":87} messageTag=99
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=4)
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=147 Len=20]
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=64 lastHopRssi=-95 relayCount=1 relayList=703
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4](ackRx=4 frmTx=4)
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=4 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=4)
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=147 Len=28]

[andzno1]

Logs part 4:

[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":194} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":56,"lastHopRssi":-97,"lastHopTimestamp":0} messageContents=010401
[2025-04-30 00:28:49] debug: 	zh:controller: Received payload: clusterID=6, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=56, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":4,"commandIdentifier":1},"payload":{},"command":{"ID":1,"parameters":[],"name":"on"}}
[2025-04-30 00:28:49] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genOnOff.defaultRsp({"cmdId":1,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":4,"writeUndiv":false})
[2025-04-30 00:28:49] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send defaultRsp request immediately (sendPolicy=undefined)
[2025-04-30 00:28:49] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":4,"commandIdentifier":11}]
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=148 Len=26]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=4 frmRx=7](ackRx=4)
[2025-04-30 00:28:49] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandOn', cluster 'genOnOff', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:49] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x84ba20fffe624d65', payload '{"action":"on","battery":null,"identify":null,"linkquality":56,"update":{"installed_version":-1,"latest_version":-1,"state":null}}'
[2025-04-30 00:28:49] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/device_automation/0x84ba20fffe624d65/action_on/config', payload '{"automation_type":"trigger","device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"payload":"on","subtype":"on","topic":"zigbee2mqtt/0x84ba20fffe624d65/action","type":"action"}'
[2025-04-30 00:28:49] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x84ba20fffe624d65/action', payload 'on'
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=4 frmTx=5)
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=5)
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=148 Len=7]
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=88 messageTag=100 status=OK]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:49] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=5)
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=148 Len=22]
[2025-04-30 00:28:49] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":88} messageTag=100
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=5)
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=148 Len=20]
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=64 lastHopRssi=-95 relayCount=1 relayList=703
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5](ackRx=5 frmTx=5)
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=5 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=5)
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=148 Len=28]
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":199} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=010500
[2025-04-30 00:28:50] debug: 	zh:controller: Received payload: clusterID=6, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=64, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":5,"commandIdentifier":0},"payload":{},"command":{"ID":0,"parameters":[],"name":"off"}}
[2025-04-30 00:28:50] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genOnOff.defaultRsp({"cmdId":0,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":5,"writeUndiv":false})
[2025-04-30 00:28:50] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send defaultRsp request immediately (sendPolicy=undefined)
[2025-04-30 00:28:50] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":5,"commandIdentifier":11}]
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=149 Len=26]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=5 frmRx=3](ackRx=5)
[2025-04-30 00:28:50] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandOff', cluster 'genOnOff', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=5 frmTx=6)
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=6)
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=149 Len=7]
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=89 messageTag=101 status=OK]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=6 frmTx=6)
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:50] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=6)
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=149 Len=22]
[2025-04-30 00:28:50] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":89} messageTag=101
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=6 frmTx=6)
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=5](frmRx=5) Added to rxQueue
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=6](ackRx=6)
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=149 Len=20]
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=68 lastHopRssi=-94 relayCount=1 relayList=703
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6](ackRx=6 frmTx=6)
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=6 frmNum=6](frmRx=6) Added to rxQueue
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=7](ackRx=6)
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=149 Len=28]
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":204} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":64,"lastHopRssi":-95,"lastHopTimestamp":0} messageContents=010601
[2025-04-30 00:28:51] debug: 	zh:controller: Received payload: clusterID=6, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=64, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":6,"commandIdentifier":1},"payload":{},"command":{"ID":1,"parameters":[],"name":"on"}}
[2025-04-30 00:28:51] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genOnOff.defaultRsp({"cmdId":1,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":6,"writeUndiv":false})
[2025-04-30 00:28:51] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send defaultRsp request immediately (sendPolicy=undefined)
[2025-04-30 00:28:51] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":6,"commandIdentifier":11}]
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=150 Len=26]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=6 frmRx=7](ackRx=6)
[2025-04-30 00:28:51] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandOn', cluster 'genOnOff', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=6 frmTx=7)
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=7](frmRx=7) Added to rxQueue
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=0](ackRx=7)
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=150 Len=7]
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=90 messageTag=102 status=OK]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=0](frmRx=0) Added to rxQueue
[2025-04-30 00:28:51] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=1](ackRx=7)
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=150 Len=22]
[2025-04-30 00:28:51] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":90} messageTag=102
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=1](frmRx=1) Added to rxQueue
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=2](ackRx=7)
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=89:"INCOMING_ROUTE_RECORD_HANDLER" Seq=150 Len=20]
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: ezspIncomingRouteRecordHandler: source=16313 sourceEui=0x84ba20fffe624d65 lastHopLqi=60 lastHopRssi=-96 relayCount=1 relayList=703
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7](ackRx=7 frmTx=7)
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=7 frmNum=2](frmRx=2) Added to rxQueue
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=3](ackRx=7)
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=69:"INCOMING_MESSAGE_HANDLER" Seq=150 Len=28]
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: ezspIncomingMessageHandler: type=UNICAST apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":256,"groupId":0,"sequence":209} packetInfo:{"senderShortId":16313,"senderLongId":"0xFFFFFFFFFFFFFFFF","bindingIndex":255,"addressIndex":255,"lastHopLqi":60,"lastHopRssi":-96,"lastHopTimestamp":0} messageContents=010700
[2025-04-30 00:28:52] debug: 	zh:controller: Received payload: clusterID=6, address=16313, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=60, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":7,"commandIdentifier":0},"payload":{},"command":{"ID":0,"parameters":[],"name":"off"}}
[2025-04-30 00:28:52] debug: 	zh:controller:endpoint: ZCL command 0x84ba20fffe624d65/1 genOnOff.defaultRsp({"cmdId":0,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":7,"writeUndiv":false})
[2025-04-30 00:28:52] debug: 	zh:controller:endpoint: Request Queue (0x84ba20fffe624d65/1): send defaultRsp request immediately (sendPolicy=undefined)
[2025-04-30 00:28:52] debug: 	zh:ember: ~~~> [ZCL to=0x84ba20fffe624d65:16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":0} header={"frameControl":{"reservedBits":0,"frameType":0,"direction":1,"disableDefaultResponse":true,"manufacturerSpecific":false},"transactionSequenceNumber":7,"commandIdentifier":11}]
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: ===> [FRAME: ID=52:"SEND_UNICAST" Seq=151 Len=26]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=DATA frmTx=7 frmRx=3](ackRx=7)
[2025-04-30 00:28:52] debug: 	z2m: Received Zigbee message from '0x84ba20fffe624d65', type 'commandOff', cluster 'genOnOff', data '{}' from endpoint 1 with groupID 0
[2025-04-30 00:28:52] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x84ba20fffe624d65', payload '{"action":"off","battery":null,"identify":null,"linkquality":60,"update":{"installed_version":-1,"latest_version":-1,"state":null}}'
[2025-04-30 00:28:52] info: 	z2m:mqtt: MQTT publish: topic 'homeassistant/device_automation/0x84ba20fffe624d65/action_off/config', payload '{"automation_type":"trigger","device":{"hw_version":1,"identifiers":["zigbee2mqtt_0x84ba20fffe624d65"],"manufacturer":"IKEA","model":"STYRBAR remote control","model_id":"E2001/E2002","name":"0x84ba20fffe624d65","sw_version":"1.0.024","via_device":"zigbee2mqtt_bridge_0x44e2f8fffe8c9ca2"},"origin":{"name":"Zigbee2MQTT","sw":"2.2.1","url":"https://www.zigbee2mqtt.io"},"payload":"off","subtype":"off","topic":"zigbee2mqtt/0x84ba20fffe624d65/action","type":"action"}'
[2025-04-30 00:28:52] info: 	z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/0x84ba20fffe624d65/action', payload 'off'
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=7 frmTx=0)
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=3](frmRx=3) Added to rxQueue
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=4](ackRx=0)
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: <=== [FRAME: ID=52:"SEND_UNICAST" Seq=151 Len=7]
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: ~~~> [SENT type=DIRECT apsSequence=91 messageTag=103 status=OK]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA]
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0](ackRx=0 frmTx=0)
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: <--- [FRAME type=DATA ackNum=0 frmNum=4](frmRx=4) Added to rxQueue
[2025-04-30 00:28:52] debug: 	zh:ember:uart:ash: ---> [FRAME type=ACK frmRx=5](ackRx=0)
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: <=== [CBFRAME: ID=63:"MESSAGE_SENT_HANDLER" Seq=151 Len=22]
[2025-04-30 00:28:52] debug: 	zh:ember:ezsp: ezspMessageSentHandler: status=OK type=DIRECT indexOrDestination=16313 apsFrame={"profileId":260,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"options":4352,"groupId":0,"sequence":91} messageTag=103

[andzno1]

I ended up unplugging the night light while pairing the remote. This resolved the issue for me.

Looks like the remote paired/bound itself with the night light during pairing. It would still be nice to know why