search

Koenkk / zigbee2mqtt

Zigbee 🐝 to MQTT bridge 🌉, get rid of your proprietary Zigbee bridges 🔨
https://www.zigbee2mqtt.io
GNU General Public License v3.0
11.85k stars 1.65k forks source link

Cannot join any new devices in version 1.40.X with Conbee III coordinator #23996

Open Ar-eh opened 2 weeks ago

Ar-eh commented 2 weeks ago

What happened?

Devices fail to be configured or interviewed upon enabling permit join and entering pairing mode on device.

What did you expect to happen?

Expected devices to be paired successfully

How to reproduce it (minimal and precise)

Enable permit join in zigbee2mqtt front end, press pairing button on Badring 4x. Light on Badring flashes as if paired successfully, but device does not appear in zigbee2mqtt.

I also tried to repair an IKEA LED1545G12 that had previously been connected to the network without success.

Zigbee2MQTT version

1.40.0 and 1.40.1

Adapter firmware version

0x26530900

Adapter

Conbee3

Setup

Docker on Synology DS718+

Debug log

[2024-09-16 21:33:28] debug: zh:deconz: PERMIT_JOIN - 254 seconds [2024-09-16 21:33:28] debug: zh:deconz: zclFrame to all - zclFrame.payload: [2024-09-16 21:33:28] debug: zh:deconz: [object Object] [2024-09-16 21:33:28] debug: zh:deconz: sendZclFrameToAll - message send [2024-09-16 21:33:28] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0xfffd EP:242 SeqNr. 144 request id: 117 [2024-09-16 21:33:28] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 117 status: 0 [2024-09-16 21:33:28] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:29] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:29] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:29] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:29] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 145 [2024-09-16 21:33:29] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xfffd request id: 117 confirm status: 0 [2024-09-16 21:33:29] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:29] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/response/permit_join', payload '{"data":{"time":254,"value":true},"status":"ok","transaction":"zrved-1"}' [2024-09-16 21:33:30] debug: zh:deconz:driver: send read device state from queue. seqNr: 146 [2024-09-16 21:33:30] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:33:30] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:34] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:34] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:34] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:34] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 147 [2024-09-16 21:33:34] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 147 srcAddr: 0xa9b9 destAddr: 0x0 profile id: 0x104 cluster id: 0xfc7d lqi: 218 [2024-09-16 21:33:34] debug: zh:deconz:frameparser: response payload: 12,124,17,112,10,4,0,33,15,0 [2024-09-16 21:33:34] debug: zh:controller: Received payload: clusterID=64637, address=43449, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=218, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":true,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":4476,"transactionSequenceNumber":112,"commandIdentifier":10},"payload":[{"attrId":4,"dataType":33,"attrData":15}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:33:34] debug: zh:controller:endpoint: ZCL command 0xdc8e95fffe5625e2/1 manuSpecificIkeaAirPurifier.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"manufacturerCode":4476,"transactionSequenceNumber":112,"writeUndiv":false}) [2024-09-16 21:33:34] debug: zh:deconz: no response expected (112) [2024-09-16 21:33:34] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:34] debug: z2m: Received Zigbee message from 'Air Purifier North (Living Room)', type 'attributeReport', cluster 'manuSpecificIkeaAirPurifier', data '{"particulateMatter25Measurement":15}' from endpoint 1 with groupID 0 [2024-09-16 21:33:35] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Air Purifier North (Living Room)', payload '{"air_quality":"good","child_lock":"UNLOCK","device_age":null,"fan_mode":"auto","fan_speed":1,"fan_state":"ON","filter_age":188355,"led_enable":true,"linkquality":218,"pm25":15,"replace_filter":false,"update":{"installed_version":69633,"latest_version":69633,"state":"idle"},"update_available":false}' [2024-09-16 21:33:35] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0xa9b9 EP:1 SeqNr. 148 request id: 118 [2024-09-16 21:33:35] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 118 status: 0 [2024-09-16 21:33:35] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:35] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:35] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:35] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:35] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 149 [2024-09-16 21:33:35] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xa9b9 request id: 118 confirm status: 0 [2024-09-16 21:33:35] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:35] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 112 [2024-09-16 21:33:35] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:33:35] debug: zh:deconz: resolve request (112) [2024-09-16 21:33:36] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:36] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:36] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:36] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 150 [2024-09-16 21:33:36] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 150 srcAddr: 0x9dbf destAddr: 0x0 profile id: 0x0 cluster id: 0x5 lqi: 205 [2024-09-16 21:33:36] debug: zh:deconz:frameparser: response payload: 210,0,0 [2024-09-16 21:33:36] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:40] debug: zh:deconz:driver: send read device state from queue. seqNr: 151 [2024-09-16 21:33:40] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:33:40] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:45] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:45] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 152 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 152 srcAddr: 0x997 destAddr: 0x0 profile id: 0x104 cluster id: 0xfc7d lqi: 212 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: response payload: 12,124,17,10,10,6,0,32,1 [2024-09-16 21:33:45] debug: zh:controller: Received payload: clusterID=64637, address=2455, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=212, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":true,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":4476,"transactionSequenceNumber":10,"commandIdentifier":10},"payload":[{"attrId":6,"dataType":32,"attrData":1}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:33:45] debug: zh:controller:endpoint: ZCL command 0xdc8e95fffe57ef00/1 manuSpecificIkeaAirPurifier.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"manufacturerCode":4476,"transactionSequenceNumber":10,"writeUndiv":false}) [2024-09-16 21:33:45] debug: zh:deconz: no response expected (10) [2024-09-16 21:33:45] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: z2m: Received Zigbee message from 'Air Purifier South (Living Room)', type 'attributeReport', cluster 'manuSpecificIkeaAirPurifier', data '{"fanMode":1}' from endpoint 1 with groupID 0 [2024-09-16 21:33:45] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Air Purifier South (Living Room)', payload '{"air_quality":"excellent","child_lock":"UNLOCK","device_age":null,"fan_mode":"auto","fan_speed":1,"fan_state":"ON","filter_age":173872,"led_enable":true,"linkquality":212,"pm25":6,"replace_filter":false,"update":{"installed_version":69633,"latest_version":69633,"state":"idle"},"update_available":false}' [2024-09-16 21:33:45] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x997 EP:1 SeqNr. 153 request id: 119 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 119 status: 0 [2024-09-16 21:33:45] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:45] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:45] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 154 [2024-09-16 21:33:45] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x997 request id: 119 confirm status: 0 [2024-09-16 21:33:45] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:45] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 10 [2024-09-16 21:33:45] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:33:45] debug: zh:deconz: resolve request (10) [2024-09-16 21:33:50] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:50] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:50] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:50] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 155 [2024-09-16 21:33:50] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 155 srcAddr: 0x94b216fffeb75d16 destAddr: 0xffff profile id: 0x0 cluster id: 0x13 lqi: 186 [2024-09-16 21:33:50] debug: zh:deconz:frameparser: response payload: 129,190,133,22,93,183,254,255,22,178,148,128 [2024-09-16 21:33:50] debug: zh:deconz: Try to find network address of 94b216fffeb75d16 [2024-09-16 21:33:50] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - AssertionError [ERR_ASSERTION]: Failed to find srcAddr of message [2024-09-16 21:33:50] debug: zh:deconz:driver: send read device state from queue. seqNr: 156 [2024-09-16 21:33:50] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:33:50] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:51] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 157 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 157 srcAddr: 0x5d39 destAddr: 0x0 profile id: 0x104 cluster id: 0x406 lqi: 197 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: response payload: 8,1,10,0,0,24,1 [2024-09-16 21:33:51] debug: zh:controller: Received payload: clusterID=1030, address=23865, groupID=0, endpoint=2, destinationEndpoint=1, wasBroadcast=false, linkQuality=197, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":1,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":24,"attrData":1}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:33:51] debug: zh:controller:endpoint: ZCL command 0x0017880108643bf4/2 msOccupancySensing.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":1,"writeUndiv":false}) [2024-09-16 21:33:51] debug: zh:deconz: no response expected (1) [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: z2m: Received Zigbee message from 'Philips Sensor (Kitchen)', type 'attributeReport', cluster 'msOccupancySensing', data '{"occupancy":1}' from endpoint 2 with groupID 0 [2024-09-16 21:33:51] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Philips Sensor (Kitchen)', payload '{"battery":100,"illuminance":0,"illuminance_lux":0,"led_indication":true,"linkquality":197,"motion_sensitivity":"high","occupancy":true,"occupancy_timeout":0,"temperature":23.08,"update":{"installed_version":1107323831,"latest_version":1107323831,"state":"idle"},"update_available":false}' [2024-09-16 21:33:51] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x5d39 EP:2 SeqNr. 158 request id: 120 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 120 status: 0 [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:51] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/Kitchen/set' with data '{"state":"ON","transition":1.0,"brightness":3,"color_temp":500}' [2024-09-16 21:33:51] debug: z2m: Publishing 'set' 'brightness' to 'Kitchen' [2024-09-16 21:33:51] debug: zh:controller:group: Command 6 genLevelCtrl.moveToLevelWithOnOff({"level":3,"transtime":0}) [2024-09-16 21:33:51] debug: zh:deconz: zclFrame to group - zclFrame.payload: [2024-09-16 21:33:51] debug: zh:deconz: [object Object] [2024-09-16 21:33:51] debug: zh:deconz: sendZclFrameToGroup - message send [2024-09-16 21:33:51] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 159 [2024-09-16 21:33:51] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x6 SeqNr. 160 request id: 121 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x5d39 request id: 120 confirm status: 0 [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 121 status: 0 [2024-09-16 21:33:51] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:51] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 1 [2024-09-16 21:33:51] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:33:51] debug: zh:deconz: resolve request (1) [2024-09-16 21:33:52] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:52] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:52] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:52] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 161 [2024-09-16 21:33:52] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x6 request id: 121 confirm status: 0 [2024-09-16 21:33:52] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:52] debug: z2m: Publishing 'set' 'transition' to 'Kitchen' [2024-09-16 21:33:52] debug: z2m: Publishing 'set' 'color_temp' to 'Kitchen' [2024-09-16 21:33:52] debug: zhc:light: Requested color_temp 500 is higher than maximum supported 454, using maximum! [2024-09-16 21:33:52] debug: zh:controller:group: Command 6 lightingColorCtrl.moveToColorTemp({"colortemp":454,"transtime":10}) [2024-09-16 21:33:52] debug: zh:deconz: zclFrame to group - zclFrame.payload: [2024-09-16 21:33:52] debug: zh:deconz: [object Object] [2024-09-16 21:33:52] debug: zh:deconz: sendZclFrameToGroup - message send [2024-09-16 21:33:52] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x6 SeqNr. 162 request id: 122 [2024-09-16 21:33:52] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 122 status: 0 [2024-09-16 21:33:52] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:53] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 163 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 163 srcAddr: 0x85be destAddr: 0x0 profile id: 0x0 cluster id: 0x2 lqi: 184 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: response payload: 130,0,0 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:53] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 164 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x6 request id: 122 confirm status: 0 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 11 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":204,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Kitchen', payload '{"brightness":3,"color_mode":"color_temp","color_temp":454,"state":"ON"}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 12 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":201,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 13 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":190,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 14 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":196,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 15 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":211,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 16 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":200,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 17 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":191,"power_on_behavior":null,"state":"ON","update":{"installed_version":587814449,"latest_version":587814449,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Bulb 18 (Kitchen)', payload '{"brightness":3,"color_mode":"color_temp","color_options":null,"color_temp":454,"level_config":{"on_level":"previous"},"linkquality":201,"power_on_behavior":null,"state":"ON","update":{"installed_version":587757105,"latest_version":587757105,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/All Downstairs Lights', payload '{"brightness":3,"color":{"x":0.5056,"y":0.4152},"color_mode":"color_temp","color_temp":454,"state":"ON"}' [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/All Lights', payload '{"brightness":3,"color":{"h":25,"hue":25,"s":95,"saturation":95,"x":0.5056,"y":0.4152},"color_mode":"color_temp","color_temp":454,"state":"ON"}' [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:53] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 165 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 165 srcAddr: 0x997 destAddr: 0x0 profile id: 0x104 cluster id: 0xfc7d lqi: 210 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: response payload: 12,124,17,11,10,4,0,33,7,0 [2024-09-16 21:33:53] debug: zh:controller: Received payload: clusterID=64637, address=2455, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=210, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":true,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":4476,"transactionSequenceNumber":11,"commandIdentifier":10},"payload":[{"attrId":4,"dataType":33,"attrData":7}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:33:53] debug: zh:controller:endpoint: ZCL command 0xdc8e95fffe57ef00/1 manuSpecificIkeaAirPurifier.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"manufacturerCode":4476,"transactionSequenceNumber":11,"writeUndiv":false}) [2024-09-16 21:33:53] debug: zh:deconz: no response expected (11) [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: z2m: Received Zigbee message from 'Air Purifier South (Living Room)', type 'attributeReport', cluster 'manuSpecificIkeaAirPurifier', data '{"particulateMatter25Measurement":7}' from endpoint 1 with groupID 0 [2024-09-16 21:33:53] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Air Purifier South (Living Room)', payload '{"air_quality":"excellent","child_lock":"UNLOCK","device_age":null,"fan_mode":"auto","fan_speed":1,"fan_state":"ON","filter_age":173872,"led_enable":true,"linkquality":210,"pm25":7,"replace_filter":false,"update":{"installed_version":69633,"latest_version":69633,"state":"idle"},"update_available":false}' [2024-09-16 21:33:53] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x997 EP:1 SeqNr. 166 request id: 123 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 123 status: 0 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:33:53] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 167 [2024-09-16 21:33:53] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x997 request id: 123 confirm status: 0 [2024-09-16 21:33:53] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:53] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 11 [2024-09-16 21:33:53] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:33:53] debug: zh:deconz: resolve request (11) [2024-09-16 21:33:59] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:33:59] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:33:59] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:33:59] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 168 [2024-09-16 21:34:00] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 168 srcAddr: 0x85be destAddr: 0xffff profile id: 0x0 cluster id: 0x36 lqi: 187 [2024-09-16 21:34:00] debug: zh:deconz:frameparser: response payload: 1,180,1 [2024-09-16 21:34:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:00] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:00] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 169 [2024-09-16 21:34:00] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 169 srcAddr: 0x85be destAddr: 0xffff profile id: 0x104 cluster id: 0x3 lqi: 186 [2024-09-16 21:34:00] debug: zh:deconz:frameparser: response payload: 1,0,1 [2024-09-16 21:34:00] debug: zh:controller: Data is from unknown device with address '34238', skipping... [2024-09-16 21:34:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:00] debug: zh:deconz:driver: send read device state from queue. seqNr: 170 [2024-09-16 21:34:00] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:34:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:04] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:34:04] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:04] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:04] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 171 [2024-09-16 21:34:04] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 171 srcAddr: 0x231c destAddr: 0x0 profile id: 0x104 cluster id: 0x19 lqi: 205 [2024-09-16 21:34:04] debug: zh:deconz:frameparser: response payload: 1,66,1,0,11,16,13,1,183,107,0,66 [2024-09-16 21:34:04] debug: zh:controller: Received payload: clusterID=25, address=8988, groupID=0, endpoint=2, destinationEndpoint=1, wasBroadcast=false, linkQuality=205, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":66,"commandIdentifier":1},"payload":{"fieldControl":0,"manufacturerCode":4107,"imageType":269,"fileVersion":1107323831},"command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}} [2024-09-16 21:34:04] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:04] debug: z2m: Received Zigbee message from 'Philips Sensor (Master Closet)', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1107323831,"imageType":269,"manufacturerCode":4107}' from endpoint 2 with groupID 0 [2024-09-16 21:34:04] debug: z2m: Device 'Philips Sensor (Master Closet)' requested OTA [2024-09-16 21:34:04] debug: zhc:ota:common: Checking if an update is available for '0x0017880108643c16' (SML001) [2024-09-16 21:34:04] debug: zhc:ota:common: Is new image available for '0x0017880108643c16' (SML001), current '{"fieldControl":0,"manufacturerCode":4107,"imageType":269,"fileVersion":1107323831}' [2024-09-16 21:34:04] debug: zhc:ota: Getting image metadata for 'SML001' [2024-09-16 21:34:05] debug: zhc:ota: Downloaded main index [2024-09-16 21:34:05] debug: zhc:ota:common: Is new image available for '0x0017880108643c16' (SML001), latest meta '{"fileVersion":1107323831,"fileSize":240760,"url":"https://otau.meethue.com/storage/ZGB_100B_010D/bd3f218f-190b-4498-b6b3-69aea563fd9d/Sensor-ATmega_6.1.1.27575_0012.sbl-ota","sha512":"10e549c55d262b2f227b75817f6620be407f537e04ac08db4dbebdf53e8d460299a2cda248b3be13476757d61a1c79b632963c4f126cd26b7c18ea6a2ab4ac59"}' [2024-09-16 21:34:05] debug: zhc:ota:common: Update available for '0x0017880108643c16' (SML001): NO [2024-09-16 21:34:05] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Philips Sensor (Master Closet)', payload '{"battery":100,"illuminance":0,"illuminance_lux":0,"led_indication":true,"linkquality":205,"motion_sensitivity":"high","occupancy":false,"occupancy_timeout":0,"temperature":22.22,"update":{"installed_version":1107323831,"latest_version":1107323831,"state":"idle"},"update_available":false}' [2024-09-16 21:34:05] debug: zh:controller:endpoint: CommandResponse 0x0017880108643c16/2 genOta.queryNextImageResponse({"status":152}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"writeUndiv":false}) [2024-09-16 21:34:05] debug: zh:deconz: no response expected (66) [2024-09-16 21:34:05] debug: z2m: Responded to OTA request of 'Philips Sensor (Master Closet)' with 'NO_IMAGE_AVAILABLE' [2024-09-16 21:34:05] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x231c EP:2 SeqNr. 172 request id: 124 [2024-09-16 21:34:05] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 124 status: 0 [2024-09-16 21:34:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:05] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:34:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:05] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:34:05] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 173 [2024-09-16 21:34:05] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x231c request id: 124 confirm status: 0 [2024-09-16 21:34:05] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:05] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 66 [2024-09-16 21:34:05] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:34:05] debug: zh:deconz: resolve request (66) [2024-09-16 21:34:07] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:34:07] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:07] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:07] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 174 [2024-09-16 21:34:07] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 174 srcAddr: 0x5d39 destAddr: 0x0 profile id: 0x104 cluster id: 0x406 lqi: 197 [2024-09-16 21:34:07] debug: zh:deconz:frameparser: response payload: 8,2,10,0,0,24,0 [2024-09-16 21:34:07] debug: zh:controller: Received payload: clusterID=1030, address=23865, groupID=0, endpoint=2, destinationEndpoint=1, wasBroadcast=false, linkQuality=197, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":2,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":24,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:34:07] debug: zh:controller:endpoint: ZCL command 0x0017880108643bf4/2 msOccupancySensing.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":2,"writeUndiv":false}) [2024-09-16 21:34:07] debug: zh:deconz: no response expected (2) [2024-09-16 21:34:07] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:07] debug: z2m: Received Zigbee message from 'Philips Sensor (Kitchen)', type 'attributeReport', cluster 'msOccupancySensing', data '{"occupancy":0}' from endpoint 2 with groupID 0 [2024-09-16 21:34:07] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Philips Sensor (Kitchen)', payload '{"battery":100,"illuminance":0,"illuminance_lux":0,"led_indication":true,"linkquality":197,"motion_sensitivity":"high","occupancy":false,"occupancy_timeout":0,"temperature":23.08,"update":{"installed_version":1107323831,"latest_version":1107323831,"state":"idle"},"update_available":false}' [2024-09-16 21:34:07] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x5d39 EP:2 SeqNr. 175 request id: 125 [2024-09-16 21:34:07] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 125 status: 0 [2024-09-16 21:34:07] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:07] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:34:07] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:07] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:34:07] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 176 [2024-09-16 21:34:07] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x5d39 request id: 125 confirm status: 0 [2024-09-16 21:34:07] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:07] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 2 [2024-09-16 21:34:07] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:34:07] debug: zh:deconz: resolve request (2) [2024-09-16 21:34:10] debug: zh:deconz:driver: send read device state from queue. seqNr: 177 [2024-09-16 21:34:10] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:34:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:34:19] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:19] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:19] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 178 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 178 srcAddr: 0xd718 destAddr: 0x0 profile id: 0x104 cluster id: 0x402 lqi: 196 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: response payload: 24,208,10,0,0,41,104,1 [2024-09-16 21:34:19] debug: zh:controller: Received payload: clusterID=1026, address=55064, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=196, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":208,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":360}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:34:19] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:19] debug: z2m: Received Zigbee message from 'Fridge Sensor (Kitchen)', type 'attributeReport', cluster 'msTemperatureMeasurement', data '{"measuredValue":360}' from endpoint 1 with groupID 0 [2024-09-16 21:34:19] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Fridge Sensor (Kitchen)', payload '{"battery":50,"humidity":73.64,"linkquality":196,"power_outage_count":86,"pressure":999.2,"temperature":3.6,"voltage":2925}' [2024-09-16 21:34:19] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:19] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 179 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 179 srcAddr: 0xd718 destAddr: 0x0 profile id: 0x104 cluster id: 0x405 lqi: 196 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: response payload: 24,209,10,0,0,33,11,26 [2024-09-16 21:34:19] debug: zh:controller: Received payload: clusterID=1029, address=55064, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=196, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":209,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":33,"attrData":6667}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:34:19] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:19] debug: z2m: Received Zigbee message from 'Fridge Sensor (Kitchen)', type 'attributeReport', cluster 'msRelativeHumidity', data '{"measuredValue":6667}' from endpoint 1 with groupID 0 [2024-09-16 21:34:19] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Fridge Sensor (Kitchen)', payload '{"battery":50,"humidity":66.67,"linkquality":196,"power_outage_count":86,"pressure":999.2,"temperature":3.6,"voltage":2925}' [2024-09-16 21:34:19] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:19] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 180 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 180 srcAddr: 0xd718 destAddr: 0x0 profile id: 0x104 cluster id: 0x403 lqi: 197 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: response payload: 24,210,10,0,0,41,231,3,20,0,40,255,16,0,41,7,39 [2024-09-16 21:34:19] debug: zh:controller: Received payload: clusterID=1027, address=55064, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=197, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":210,"commandIdentifier":10},"payload":[{"attrId":0,"dataType":41,"attrData":999},{"attrId":20,"dataType":40,"attrData":-1},{"attrId":16,"dataType":41,"attrData":9991}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}} [2024-09-16 21:34:19] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:19] debug: z2m: Received Zigbee message from 'Fridge Sensor (Kitchen)', type 'attributeReport', cluster 'msPressureMeasurement', data '{"measuredValue":999,"scale":-1,"scaledValue":9991}' from endpoint 1 with groupID 0 [2024-09-16 21:34:19] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Fridge Sensor (Kitchen)', payload '{"battery":50,"humidity":66.67,"linkquality":197,"power_outage_count":86,"pressure":999.1,"temperature":3.6,"voltage":2925}' [2024-09-16 21:34:19] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:19] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 181 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 181 srcAddr: 0x7dd4 destAddr: 0x0 profile id: 0x0 cluster id: 0x5 lqi: 229 [2024-09-16 21:34:19] debug: zh:deconz:frameparser: response payload: 68,0,0 [2024-09-16 21:34:19] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:20] debug: zh:deconz:driver: send read device state from queue. seqNr: 182 [2024-09-16 21:34:20] debug: zh:deconz:frameparser: device state: 100010 [2024-09-16 21:34:20] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:34:22] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:22] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 183 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 183 srcAddr: 0xcc8a destAddr: 0x0 profile id: 0x104 cluster id: 0x19 lqi: 203 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: response payload: 1,103,1,0,11,16,13,1,183,107,0,66 [2024-09-16 21:34:22] debug: zh:controller: Received payload: clusterID=25, address=52362, groupID=0, endpoint=2, destinationEndpoint=1, wasBroadcast=false, linkQuality=203, frame={"header":{"frameControl":{"frameType":1,"manufacturerSpecific":false,"direction":0,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":103,"commandIdentifier":1},"payload":{"fieldControl":0,"manufacturerCode":4107,"imageType":269,"fileVersion":1107323831},"command":{"ID":1,"response":2,"parameters":[{"name":"fieldControl","type":32},{"name":"manufacturerCode","type":33},{"name":"imageType","type":33},{"name":"fileVersion","type":35}],"name":"queryNextImageRequest"}} [2024-09-16 21:34:22] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: z2m: Received Zigbee message from 'Philips Sensor (Downstairs Hall)', type 'commandQueryNextImageRequest', cluster 'genOta', data '{"fieldControl":0,"fileVersion":1107323831,"imageType":269,"manufacturerCode":4107}' from endpoint 2 with groupID 0 [2024-09-16 21:34:22] debug: z2m: Device 'Philips Sensor (Downstairs Hall)' requested OTA [2024-09-16 21:34:22] debug: zhc:ota:common: Checking if an update is available for '0x0017880106f73d5c' (SML001) [2024-09-16 21:34:22] debug: zhc:ota:common: Is new image available for '0x0017880106f73d5c' (SML001), current '{"fieldControl":0,"manufacturerCode":4107,"imageType":269,"fileVersion":1107323831}' [2024-09-16 21:34:22] debug: zhc:ota: Getting image metadata for 'SML001' [2024-09-16 21:34:22] debug: zhc:ota: Downloaded main index [2024-09-16 21:34:22] debug: zhc:ota:common: Is new image available for '0x0017880106f73d5c' (SML001), latest meta '{"fileVersion":1107323831,"fileSize":240760,"url":"https://otau.meethue.com/storage/ZGB_100B_010D/bd3f218f-190b-4498-b6b3-69aea563fd9d/Sensor-ATmega_6.1.1.27575_0012.sbl-ota","sha512":"10e549c55d262b2f227b75817f6620be407f537e04ac08db4dbebdf53e8d460299a2cda248b3be13476757d61a1c79b632963c4f126cd26b7c18ea6a2ab4ac59"}' [2024-09-16 21:34:22] debug: zhc:ota:common: Update available for '0x0017880106f73d5c' (SML001): NO [2024-09-16 21:34:22] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/Philips Sensor (Downstairs Hall)', payload '{"battery":9.5,"illuminance":2464,"illuminance_lux":2,"led_indication":true,"linkquality":203,"motion_sensitivity":"high","occupancy":false,"occupancy_timeout":0,"temperature":22.68,"update":{"installed_version":1107323831,"latest_version":1107323831,"state":"idle"},"update_available":false}' [2024-09-16 21:34:22] debug: zh:controller:endpoint: CommandResponse 0x0017880106f73d5c/2 genOta.queryNextImageResponse({"status":152}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"writeUndiv":false}) [2024-09-16 21:34:22] debug: zh:deconz: no response expected (103) [2024-09-16 21:34:22] debug: z2m: Responded to OTA request of 'Philips Sensor (Downstairs Hall)' with 'NO_IMAGE_AVAILABLE' [2024-09-16 21:34:22] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0xcc8a EP:2 SeqNr. 184 request id: 126 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 126 status: 0 [2024-09-16 21:34:22] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: DEVICE_STATE changed: 100110 [2024-09-16 21:34:22] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: zh:deconz:driver: query aps data confirm [2024-09-16 21:34:22] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 185 [2024-09-16 21:34:22] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0xcc8a request id: 126 confirm status: 0 [2024-09-16 21:34:22] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:22] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 103 [2024-09-16 21:34:22] debug: zh:deconz: false, true, false, 10000 [2024-09-16 21:34:22] debug: zh:deconz: resolve request (103) [2024-09-16 21:34:28] debug: zh:deconz:frameparser: DEVICE_STATE changed: 101010 [2024-09-16 21:34:28] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:28] debug: zh:deconz:driver: query aps data indication [2024-09-16 21:34:28] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 186 [2024-09-16 21:34:28] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 186 srcAddr: 0x6aa1 destAddr: 0x0 profile id: 0x0 cluster id: 0x5 lqi: 184 [2024-09-16 21:34:28] debug: zh:deconz:frameparser: response payload: 144,0,0 [2024-09-16 21:34:28] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1 [2024-09-16 21:34:30] debug: zh:deconz:driver: send read device state from queue. seqNr: 187 [2024-09-16 21:34:30] debug: zh:deconz:frameparser: device state: 100010

Ar-eh commented 2 weeks ago

I discovered that I could not pair any devices on 1.40.1 or 1.40.0. Rolling back to 1.39.1 solved the issue and allowed me to pair Badring as well as other devices. So, it is a bug introduced in 1.40.X, perhaps only for deconz coordinators?

Holovin commented 1 week ago

I can confirm same issue with E2002 remote + z2m 1.40-1 and conbee 3 stick

Ar-eh commented 1 week ago

I can confirm same issue with E2002 remote + z2m 1.40-1 and conbee 3 stick

I edited the title of the issue and description to more accurately reflect the problem.

risiko79 commented 1 week ago

Can also confirm this bug. Also other issues with 1.40.x and Conbee (EmberZNet) Rolling back to 1.39.1 - all is working fine,

Koenkk commented 1 week ago

Could you provide the debug log of joining a device both with 1.39.1 and 1.40.1?

See this on how to enable debug logging.

Ar-eh commented 1 week ago

Could you provide the debug log of joining a device both with 1.39.1 and 1.40.1?

See this on how to enable debug logging.

The debug log for attempting to join a device in 1.40.1 is in the original post. I’ll post the debug log for joining a device on 1.39.1 later today unless someone else beats me to it.

markxroberts commented 1 week ago

I don't know whether this is related...will open another issue if not. I have Conbee II running latest firmware. With Z2M 1.40.1 but not Z2M 1.40.0, I get repeated MQTT timeout errors, and many devices fail to check in or fail to receive messages. I've downgraded to 1.40.0 and this behaviour disappears. Logs can follow if these would be helpful.

SpiGAndromeda commented 1 week ago

I can confirm the issue with Z2M 1.40.

I migrated my Conbee II to a Conbee III (latest firmware) today. The network was instantly unstable (migrated the network with the Phoscon App). I dont have that much devices so resetting the whole network isn't that much of a problem. It didn't work. I couldn't pair any new devices (thats only partly true as described below).

So I switched from Z2M to ZHA and I could pair all devices effortless. Unfortunatly my thermostats are not well supported by ZHA so I wanted to switch back.

I wanted to pair the devices with constant power first but it didn't work with a single device. So I just paired a sensor and it worked instantly. Restoring a backup with Z2M 1.39 resolved the problems. Everything is working fine.

The problem seems to affect only routing devices for me. There were no pairing issues with errors. Z2M just did ... nothing. It didn't show anything and there wasn't anything in the logs.

@markxroberts might have a point as well. I lately had problems with the Zigbee network. Some routers that were routed through at least one other router were unstable. The bulbs (IKEA Tradfri) were sometimes not responding. I think the problems might have started with the Update from 1.39 to 1.40. Timing would match but I am not sure about that.

Koenkk commented 1 week ago

@SpiGAndromeda are you able to pair device to z2m 1.40.1 + Conbee II? (is it specific to the Conbee III)?

markxroberts commented 1 week ago

I can pair devices to Conbee II with 1.40.1

SpiGAndromeda commented 1 week ago

@Koenkk I can pair devices with Conbee II.

Koenkk commented 1 week ago

I see, did pairing with Conbee 3 work with older versions of z2m?

SpiGAndromeda commented 1 week ago

I see, did pairing with Conbee 3 work with older versions of z2m?

Yes. I am currently using 1.39.1-1 as Addon in HA. I could pair all my devices.

Koenkk commented 1 week ago

Great, then I need this: https://github.com/Koenkk/zigbee2mqtt/issues/23996#issuecomment-2361841895

Please also try with the latest dev branch, this might fix it.

SpiGAndromeda commented 1 week ago

Great, then I need this: #23996 (comment)

Please also try with the latest dev branch, this might fix it.

For 1.39 with Conbee II, for 1.40 with Conbee II? For Conbee III I could only provide logs for a pairing attempt. I don't want to reset the network because the Conbee III is in productive use. So Conbee III with 1.39 and/or 1.40?

Koenkk commented 1 week ago

Both Conbee III with 1.39.1 (working) and 1.40.1 (not working)

notjosh commented 4 days ago

fwiw, I'm on 1.40.1 with Conbee III and can add some devices (coincidentally had to re-pair a LED driver - worked fine), but not others (Tretakt - doesn't interview).

I had the Tretakt paired yesterday, but I wanted to re-pair it so I reset. I can confirm it did work previously, but didn't now.

The relevant log lines referring to the device/failure look like (from the first post, but same for me locally):

[2024-09-16 21:33:50] debug: zh:deconz: Try to find network address of 94b216fffeb75d16
[2024-09-16 21:33:50] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - AssertionError [ERR_ASSERTION]: Failed to find srcAddr of message

Edit: I believe it's to do with this commit, as we see the log of "Try to find network address...": https://github.com/Koenkk/zigbee-herdsman/commit/14b7b537f15f8dfbeb6b615fec55cbd2805ddff3.

Edit 2: Confirmed that this is the problem. I was able to pair Tretakt devices again after hacking the old behaviour back in:

$ vi ./node_modules/zigbee-herdsman/dist/adapter/deconz/adapter/deconzAdapter.js

# edit the line to add a fallback to the default value: `|| resp.srcAddr64`
srcAddr = device_1.default.byIeeeAddr('0x' + resp.srcAddr64, false)?.networkAddress || resp.srcAddr64;

# now restart the container to reload the source file, and try to pair again.

I have no idea if this causes other problems so I won't submit a PR or anything, but it at least fixes the pairing again in this specific instance. 🤠🤠🤠

Koenkk commented 3 days ago

@schrluka looks like it was introduced in https://github.com/Koenkk/zigbee-herdsman/commit/14b7b537f15f8dfbeb6b615fec55cbd2805ddff3, could you take a look at this?

fjumeaux commented 3 days ago

Hello. I don't know if my problem is the same but it doesn't work with the-dev commit [15d845b] and my conbee3.

Attached, a attemps of join a sonoff zbmini2 from the beginning of permit of join until the end.

log.log

To test, I also tried to join a plug OSRAM AB3257001NJ : work perfectly.

But nothing with : Sonoff zbmini 2 (extreme) Sonoff SNZP-06P Ikea VALLHORN

fjumeaux commented 3 days ago

forgot to say that it worked perfectly before... But I don't know before which commit

schrluka commented 2 days ago

Hi Everyone

@Koenkk asked me to look at this. Looks like some restructuring might have broken something in heardsmean. My suggestions is in this PR: https://github.com/Koenkk/zigbee-herdsman/pull/1207

It's untested so far as I don't have access to the appropriate hardware ATM. I will try to check this next week, but I can't promise.

BR, Lukas