hello. I have a problem with 2 mhcozy 4-way smartswitches.
They have been in place for over 6 months without any problems, but for a week now the orders sent by an automation have not all been executed. I changed a smartsitch but the problems are the same.
At 9 am I have an automation that switches line 1 to ON. In the same time L4 swtich ton OFF, because i configure the smartswitch in this mode. But the button on HA does not switch and therefore the devices doesnt not switch
[2024-11-18 09:00:00] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/smartswitch-03/l1/set' with data 'ON'
[2024-11-18 09:00:00] debug: z2m: Publishing 'set' 'state' to 'smartswitch-03'
[2024-11-18 09:00:00] debug: zh:controller:endpoint: ZCL command 0xa4c138051ea80f07/1 genOnOff.on({}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":false,"direction":0,"reservedBits":0,"writeUndiv":false})
[2024-11-18 09:00:00] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x7fa9 EP:1 SeqNr. 186 request id: 177
[2024-11-18 09:00:00] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 177 status: 0
[2024-11-18 09:00:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:00] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 177 status: 0
[2024-11-18 09:00:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:00] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-11-18 09:00:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:00] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-11-18 09:00:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:00] debug: zh:deconz:driver: query aps data confirm
[2024-11-18 09:00:00] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 187
[2024-11-18 09:00:00] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x7fa9 request id: 177 confirm status: 0
[2024-11-18 09:00:00] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:00] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 169
[2024-11-18 09:00:00] debug: zh:deconz: false, false, false, 10000
[2024-11-18 09:00:00] debug: zh:deconz: resolve request (169)
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-11-18 09:00:02] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-11-18 09:00:02] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:02] debug: zh:deconz:driver: query aps data indication
[2024-11-18 09:00:02] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 188
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 188 srcAddr: 0x3efa destAddr: 0x0 profile id: 0x104 cluster id: 0xb04 lqi: 255
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: response payload: 18790a080521a601
[2024-11-18 09:00:02] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:02] debug: zh:controller: Received payload: clusterID=2820, address=16122, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":121,"commandIdentifier":10},"payload":[{"attrId":1288,"dataType":33,"attrData":422}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 188 srcAddr: 0x3efa destAddr: 0x0 profile id: 0x104 cluster id: 0xb04 lqi: 255
[2024-11-18 09:00:02] debug: zh:deconz:frameparser: response payload: 18790a080521a601
[2024-11-18 09:00:02] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:02] debug: zh:controller: Received payload: clusterID=2820, address=16122, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"transactionSequenceNumber":121,"commandIdentifier":10},"payload":[{"attrId":1288,"dataType":33,"attrData":422}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
In the logs, 10 seconds later I have an error message it seems to me
[2024-11-18 09:00:10] error: z2m: Publish 'set' 'state' to 'smartswitch-03' failed: 'Error: ZCL command 0xa4c138051ea80f07/1 genOnOff.on({}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":false,"direction":0,"reservedBits":0,"writeUndiv":false}) failed (no response received (169) Error: waiting for response TIMEOUT)'
[2024-11-18 09:00:10] debug: z2m: Error: ZCL command 0xa4c138051ea80f07/1 genOnOff.on({}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":false,"direction":0,"reservedBits":0,"writeUndiv":false}) failed (no response received (169) Error: waiting for response TIMEOUT)
at DeconzAdapter.sendZclFrameToEndpoint (/app/node_modules/zigbee-herdsman/src/adapter/deconz/adapter/deconzAdapter.ts:485:19)
at Request.send (/app/node_modules/zigbee-herdsman/src/controller/helpers/request.ts:82:20)
at Endpoint.sendRequest (/app/node_modules/zigbee-herdsman/src/controller/model/endpoint.ts:325:20)
at Endpoint.zclCommand (/app/node_modules/zigbee-herdsman/src/controller/model/endpoint.ts:951:28)
at Endpoint.command (/app/node_modules/zigbee-herdsman/src/controller/model/endpoint.ts:723:23)
at Object.convertSet (/app/node_modules/zigbee-herdsman-converters/src/converters/toZigbee.ts:49:17)
at Publish.onMQTTMessage (/app/lib/extension/publish.ts:306:36)
at EventEmitter.wrappedCallback (/app/lib/eventBus.ts:206:17)
[2024-11-18 09:00:10] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/bridge/log', payload '{"message":"Publish 'set' 'state' to 'smartswitch-03' failed: 'Error: ZCL command 0xa4c138051ea80f07/1 genOnOff.on({}, {\"timeout\":10000,\"disableResponse\":false,\"disableRecovery\":false,\"disableDefaultResponse\":false,\"direction\":0,\"reservedBits\":0,\"writeUndiv\":false}) failed (no response received (169) Error: waiting for response TIMEOUT)'","meta":{"friendly_name":"smartswitch-03"},"type":"zigbee_publish_error"}'
[2024-11-18 09:00:10] debug: zh:deconz:driver: DATA_REQUEST - destAddr: 0x3efa EP:1 SeqNr. 203 request id: 181
[2024-11-18 09:00:10] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 181 status: 0
[2024-11-18 09:00:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:10] debug: zh:deconz:frameparser: DATA_REQUEST RESPONSE - request id: 181 status: 0
[2024-11-18 09:00:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:10] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-11-18 09:00:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:10] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10100110
[2024-11-18 09:00:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 1 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:10] debug: zh:deconz:driver: query aps data confirm
[2024-11-18 09:00:10] debug: zh:deconz:driver: DATA_CONFIRM - sending data state request - SeqNr. 204
[2024-11-18 09:00:10] debug: zh:deconz:frameparser: DATA_CONFIRM RESPONSE - destAddr: 0x3efa request id: 181 confirm status: 0
[2024-11-18 09:00:10] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:10] debug: zh:deconz: sendZclFrameToEndpoint - message send with transSeq Nr.: 122
[2024-11-18 09:00:10] debug: zh:deconz: false, true, false, 10000
[2024-11-18 09:00:10] debug: zh:deconz: resolve request (122)
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-11-18 09:00:15] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: DEVICE_STATE changed: 10101010
[2024-11-18 09:00:15] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 1 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:15] debug: zh:deconz:driver: query aps data indication
[2024-11-18 09:00:15] debug: zh:deconz:driver: DATA_INDICATION - sending read data request - SeqNr. 205
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 205 srcAddr: 0x9b74 destAddr: 0x0 profile id: 0x104 cluster id: 0xb04 lqi: 255
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: response payload: 08930a050521e90008052148000b05290000
[2024-11-18 09:00:15] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:15] debug: zh:controller: Received payload: clusterID=2820, address=39796, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":147,"commandIdentifier":10},"payload":[{"attrId":1285,"dataType":33,"attrData":233},{"attrId":1288,"dataType":33,"attrData":72},{"attrId":1291,"dataType":41,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
[2024-11-18 09:00:15] debug: zh:controller:endpoint: ZCL command 0xa4c13891e16a16ff/1 haElectricalMeasurement.defaultRsp({"cmdId":10,"statusCode":0}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":true,"direction":1,"reservedBits":0,"transactionSequenceNumber":147,"writeUndiv":false})
[2024-11-18 09:00:15] debug: zh:deconz: no response expected (147)
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: DATA_INDICATION RESPONSE - seqNr. 205 srcAddr: 0x9b74 destAddr: 0x0 profile id: 0x104 cluster id: 0xb04 lqi: 255
[2024-11-18 09:00:15] debug: zh:deconz:frameparser: response payload: 08930a050521e90008052148000b05290000
[2024-11-18 09:00:15] debug: zh:deconz:driver: networkstate: 2 apsDataConfirm: 0 apsDataIndication: 0 configChanged: 0 apsRequestFreeSlots: 1
[2024-11-18 09:00:15] debug: zh:controller: Received payload: clusterID=2820, address=39796, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=255, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"transactionSequenceNumber":147,"commandIdentifier":10},"payload":[{"attrId":1285,"dataType":33,"attrData":233},{"attrId":1288,"dataType":33,"attrData":72},{"attrId":1291,"dataType":41,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}