Merci pour ton retour. Donc z2m relance automatiquement zigbee2mqtt ?
J’ai récupéré quelques logs que j’avais mis en mode DEBUG et j’ai vu quelque chose d’interessant
Tout d’abord, la commande d’allumage de la lampe n’est pas passée :
|[2024-08-17 20:33:02] debug: |z2m: Publishing 'set' 'state' to 'LampeRGBSalon'|
|---|---|
|[2024-08-17 20:33:02] debug: |zh:controller:endpoint: ZCL command 0xa4c1388b115429f6/1 genOnOff.on({}, {timeout:10000,disableResponse:false,disableRecovery:false,disableDefaultResponse:false,direction:0,srcEndpoint:null,reservedBits:0,manufacturerCode:null,transactionSequenceNumber:null,writeUndiv:false})|
Ensuite , apres redemarrage Daemon , la commande passe :
[2024-08-17 21:18:01] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/0xa4c1388b115429f6/set' with data '{"state":"ON"}'
[2024-08-17 21:18:01] debug: z2m: Publishing 'set' 'state' to 'LampeRGBSalon'
[2024-08-17 21:18:01] debug: zh:controller:endpoint: ZCL command 0xa4c1388b115429f6/1 genOnOff.on({}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":false,"direction":0,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
[2024-08-17 21:18:01] debug: zh:ezsp: sendZclFrameToEndpointInternal 0xa4c1388b115429f6:12482/1 (0,0,2), timeout=10000
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: ==> sendUnicast: {"type":0,"indexOrDestination":12482,"apsFrame":{"profileId":260,"sequence":9,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"messageTag":10,"message":{"type":"Buffer","data":[1,5,1]}}
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: ==> {"_cls_":"sendUnicast","_id_":52,"_isRequest_":true,"type":0,"indexOrDestination":12482,"apsFrame":{"profileId":260,"sequence":9,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"messageTag":10,"message":{"type":"Buffer","data":[1,5,1]}}
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> DATA (5,0,0): 350001340000c23004010600010100010000090a03010501
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> [507721a9602a157069904b23aa5493499d4e27a2e7cd668efc29447e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: -?- waiting (6)
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- [0677a1a9602a154286957e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- DATA (0,6,0): 0677a1a9602a154286957e
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> ACK (1)
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> [8160597e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- ACK (6): 0677a1a9602a154286957e
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== Frame: 358001340000f0
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== 0x34: {"_cls_":"sendUnicast","_id_":52,"_isRequest_":false,"status":0,"sequence":240}
[2024-08-17 21:18:01] debug: zh:ezsp:uart: -+- waiting (6) success
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- [1677b1a96b2a157069904b23aa5493499c4e275be7ce679edb7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- DATA (1,6,0): 1677b1a96b2a157069904b23aa5493499c4e275be7ce679edb7e
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> ACK (2)
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> [82503a7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- ACK (6): 1677b1a96b2a157069904b23aa5493499c4e275be7ce679edb7e
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== Frame: 3590013f0000c23004010600010100000000f00a0000
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== 0x3f: {"_cls_":"messageSentHandler","_id_":63,"_isRequest_":false,"type":0,"indexOrDestination":12482,"apsFrame":{"profileId":260,"sequence":240,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":0},"messageTag":10,"status":0,"message":{"type":"Buffer","data":[]}}
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- [2677b1a90d2ad782afbd1e34216d53ed1cf227955d7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- DATA (2,6,0): 2677b1a90d2ad782afbd1e34216d53ed1cf227955d7e
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> ACK (3)
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> [83401b7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- ACK (6): 2677b1a90d2ad782afbd1e34216d53ed1cf227955d7e
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== Frame: 3590015900c230f62954118b38c1a480bc00
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== 0x59: {"_cls_":"incomingRouteRecordHandler","_id_":89,"_isRequest_":false,"source":12482,"longId":{"type":"Buffer","data":[164,193,56,139,17,84,41,246]},"lastHopLqi":128,"lastHopRssi":-68,"relay":{"type":"Buffer","data":[]}}
[2024-08-17 21:18:01] debug: zh:ezsp:driv: handleRouteRecord: nwk=12482, ieee=��8�T)�, lqi=128, rssi=-68, relays=
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- [3677b1a97d312a15b658924a24ab5593499c77a7172ffe9874f8de6682fd7d5e3d980a7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- DATA (3,6,0): 3677b1a9112a15b658924a24ab5593499c77a7172ffe9874f8de6682fd7e3d980a7e
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> ACK (4)
[2024-08-17 21:18:01] debug: zh:ezsp:uart: --> [8430fc7e]
[2024-08-17 21:18:01] debug: zh:ezsp:uart: <-- ACK (6): 3677b1a9112a15b658924a24ab5593499c77a7172ffe9874f8de6682fd7e3d980a7e
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== Frame: 359001450000040106000101000100003980bcc230ffff0518050b010002
[2024-08-17 21:18:01] debug: zh:ezsp:ezsp: <== 0x45: {"_cls_":"incomingMessageHandler","_id_":69,"_isRequest_":false,"type":0,"apsFrame":{"profileId":260,"sequence":57,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lastHopLqi":128,"lastHopRssi":-68,"sender":12482,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[24,5,11,1,0]}}
[2024-08-17 21:18:01] debug: zh:ezsp: processMessage: {"messageType":0,"apsFrame":{"profileId":260,"sequence":57,"clusterId":6,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lqi":128,"rssi":-68,"sender":12482,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[24,5,11,1,0]}}
[2024-08-17 21:18:01] debug: zh:controller: Received payload: clusterID=6, address=12482, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=128, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":true,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":5,"commandIdentifier":11},"payload":{"cmdId":1,"statusCode":0},"command":{"ID":11,"name":"defaultRsp","parameters":[{"name":"cmdId","type":32},{"name":"statusCode","type":32}]}}
[2024-08-17 21:18:01] debug: z2m: Device 'LampeRGBSalon' reconnected
[2024-08-17 21:18:01] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/LampeRGBSalon/availability', payload '{"state":"online"}'
[2024-08-17 21:18:01] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/LampeRGBSalon', payload '{"brightness":152,"color":{"x":0.4657,"y":0.4118},"color_mode":"color_temp","color_temp":380,"device":{"applicationVersion":101,"dateCode":"","friendlyName":"LampeRGBSalon","hardwareVersion":1,"ieeeAddr":"0xa4c1388b115429f6","manufacturerID":4417,"manufacturerName":"_TZ3210_ifga63rg","model":"TS0505B_1","networkAddress":12482,"powerSource":"Mains (single phase)","stackVersion":0,"type":"Router","zclVersion":3},"last_seen":"2024-08-17T19:18:01.509Z","linkquality":128,"state":"OFF"}'
[2024-08-17 21:18:01] info: z2m:mqtt: MQTT publish: topic 'zigbee2mqtt/LampeRGBSalon', payload '{"brightness":152,"color":{"x":0.4657,"y":0.4118},"color_mode":"color_temp","color_temp":380,"device":{"applicationVersion":101,"dateCode":"","friendlyName":"LampeRGBSalon","hardwareVersion":1,"ieeeAddr":"0xa4c1388b115429f6","manufacturerID":4417,"manufacturerName":"_TZ3210_ifga63rg","model":"TS0505B_1","networkAddress":12482,"powerSource":"Mains (single phase)","stackVersion":0,"type":"Router","zclVersion":3},"last_seen":"2024-08-17T19:18:01.509Z","linkquality":128,"state":"ON"}'
[2024-08-17 21:16:26] debug: z2m:mqtt: Received MQTT message on 'zigbee2mqtt/0xa4c1388b115429f6/set' with data '{"state":"ON"}'
[2024-08-17 21:16:26] debug: z2m: Publishing 'set' 'state' to 'LampeRGBSalon'
[2024-08-17 21:16:26] debug: zh:controller:endpoint: ZCL command 0xa4c1388b115429f6/1 genOnOff.on({}, {"timeout":10000,"disableResponse":false,"disableRecovery":false,"disableDefaultResponse":false,"direction":0,"srcEndpoint":null,"reservedBits":0,"manufacturerCode":null,"transactionSequenceNumber":null,"writeUndiv":false})
[2024-08-17 21:16:26] debug: zh:ezsp:uart: <-- [46dfb1a97d312a15b658944a24ab5593499cee531285829874f2cea583fd7d5e1f670932fe596b00e7dbd7c72b7e]
[2024-08-17 21:16:26] debug: zh:ezsp:uart: <-- DATA (4,6,0): 46dfb1a9112a15b658944a24ab5593499cee531285829874f2cea583fd7e1f670932fe596b00e7dbd7c72b7e
[2024-08-17 21:16:26] debug: zh:ezsp:uart: --> ACK (5)
[2024-08-17 21:16:26] debug: zh:ezsp:uart: --> [8520dd7e]
[2024-08-17 21:16:26] debug: zh:ezsp:uart: <-- ACK (6): 46dfb1a9112a15b658944a24ab5593499cee531285829874f2cea583fd7e1f670932fe596b00e7dbd7c72b7e
[2024-08-17 21:16:26] debug: zh:ezsp:ezsp: <== Frame: 9d900145000004010000010100010000a074b9684cffff0f08c60a010020c0e2ff2036e4ff200002
[2024-08-17 21:16:26] debug: zh:ezsp:ezsp: <== 0x45: {"_cls_":"incomingMessageHandler","_id_":69,"_isRequest_":false,"type":0,"apsFrame":{"profileId":260,"sequence":160,"clusterId":0,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lastHopLqi":116,"lastHopRssi":-71,"sender":19560,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[8,198,10,1,0,32,192,226,255,32,54,228,255,32,0]}}
[2024-08-17 21:16:26] debug: zh:ezsp: processMessage: {"messageType":0,"apsFrame":{"profileId":260,"sequence":160,"clusterId":0,"sourceEndpoint":1,"destinationEndpoint":1,"groupId":0,"options":256},"lqi":116,"rssi":-71,"sender":19560,"bindingIndex":255,"addressIndex":255,"message":{"type":"Buffer","data":[8,198,10,1,0,32,192,226,255,32,54,228,255,32,0]}}
[2024-08-17 21:16:26] debug: zh:controller: Received payload: clusterID=0, address=19560, groupID=0, endpoint=1, destinationEndpoint=1, wasBroadcast=false, linkQuality=116, frame={"header":{"frameControl":{"frameType":0,"manufacturerSpecific":false,"direction":1,"disableDefaultResponse":false,"reservedBits":0},"manufacturerCode":null,"transactionSequenceNumber":198,"commandIdentifier":10},"payload":[{"attrId":1,"dataType":32,"attrData":192},{"attrId":65506,"dataType":32,"attrData":54},{"attrId":65508,"dataType":32,"attrData":0}],"command":{"ID":10,"name":"report","parameters":[{"name":"attrId","type":33},{"name":"dataType","type":32},{"name":"attrData","type":1000}]}}
On voit bien que ca se bloque soit dans zh:controller, soit dans zh:ezsp
Ce qui est troublant c’est que la remontée des infos continuent de fonctionner et que certaines commandes passent toujours a priori
Je vais continuer a creuser. Je vais rajouter mes logs. Comme le phenomene reste aléatoire et rare, ca risque de prendre quelques temps…
Bonne journée