Erreur sur le plugin Zigbee

sebfar · Juin 12, 2021, 8:39

Bonjour

Le plugin Zigbee est très instable chez moi. Je suis en stable de jeedom et du plugin avec une clé Popp (je l’ai mis à jour). J’ai laissé les paramètres par défaut. Je suis sur jeedom smart. J’ai mis la clé sur un hub usb auto alimenté.

J’ai plein d’erreurs qui arrivent au moment je déclenche un interrupteur legrand par exemple.

Par exemple ci dessous :

EZSP is not running
Ou
ApplicationControler is not running

Tonio16 · Juin 13, 2021, 5:33

Salut

Tu n’as bien tagué le plugin.

Sinon pour ton souci, si ce n’est pas encore fait, brancher la clé sur un hub autoalimenté, de préférence, et/ou une ralonge USB.

Voir aussi les caneaux wifi vs zigbee.

Antoine

sebfar · Juin 13, 2021, 7:07

Bonjour
C’est déjà le cas …

olive · Juin 13, 2021, 10:37

Bonjour,

C’est pas très claire …
Ton hub dispose t’il de sa propre alimentation ou puise t’il l’energie sur le port usb ?

bon dimanche

sebfar · Juin 13, 2021, 11:59

il a sa propre alimentation

sebfar · Juin 15, 2021, 1:21

Est ce que les erreurs de mon premier message sont connus de quelqu’un … ?

FBAF · Juin 15, 2021, 6:20

Bonsoir @sebfar,

As-tu essayé sans le hub ? Et éventuellement juste avec une « rallonge » USB ?

En parallèle version de jeedom ?

belgotux · Août 8, 2021, 12:14

Hello,

As-tu pu investiguer sur le souci ?

Il y a 2 semaines j’ai eu 2 plantages d’une seule des 2 clés 2 nuits de suite : la popp (l’autre une zigate). J’ai redémarré et puis j’ai été tranquille jusque cette nuit encore.
J’avais activé les log debug et j’espérai y voir quelque chose, mais j’avais pas eu assez d’historique au moment où je me suis levé (bas oui c’est dimanche grasse mat’ )… Ca s’est passé entre 2h et 12h en tout cas.

Aujourd’hui ça s’est passé plus tard et j’ai pu avoir des logs!

Quand je vais dans le Réseaux Zigbee l’erreur suivante apparait pour la clé popp :

Erreur lors de la requete : http://127.0.0.1:8090/application/info(GET), data : null erreur : {"state":"error","result":"EZSP is not running","code":0}

Ce qui est frustrant c’est que le plugin n’est même pas mis en erreur!

Les paramètres de clés :

Quand je regarde le daemon écoute toujours sur son port réseau et le processus est lancé : (je ne sais pas quoi lui passer comme commande pour le tester)

Mais vu que j’ai des périphériques qui sont scannés toutes les 180minutes, j’ai eu mes messages à 14h, donc ça doit se situer vers 11h.

J’ai regardé un peu les logs, rien vu sur le debug. En info j’ai vu un event qui me SEMBLE dire que tout est ok, un scan des devices à 11h00 puis à 11h02 :

[2021-08-08 11:00:12][INFO] : 200 GET /device/all (127.0.0.1) 118.91ms
\x00\x00 \xfe']
[2021-08-08 11:00:38][INFO] : [cc:86:ec:ff:fe:5d:62:26][listener.general_command] Cluster: <zigpy.zcl.clusters.general.LevelControl object at 0x73beeab0> cluster_id: 0x0008 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=116 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=0, value=<TypeValue type=uint8_t, value=254>)]],)
[2021-08-08 11:00:38][INFO] : [cc:86:ec:ff:fe:5d:62:26][listener.attribute_updated] Received an attribute update 0=254 on cluster 8
\x01\x00 B']
[2021-08-08 11:00:38][INFO] : [84:fd:27:ff:fe:39:e3:9e][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73bd5fd0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=44 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:00:38][INFO] : [84:fd:27:ff:fe:39:e3:9e][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
\x03\x00!\xbc&\x04\x00!\xaeW']
[2021-08-08 11:00:43][INFO] : [cc:86:ec:ff:fe:5d:62:23][listener.general_command] Cluster: <zigpy.zcl.clusters.lighting.Color object at 0x73bee8f0> cluster_id: 0x0300 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=76 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=3, value=<TypeValue type=uint16_t, value=9916>), Attribute(attrid=4, value=<TypeValue type=uint16_t, value=22446>)]],)
[2021-08-08 11:00:43][INFO] : [cc:86:ec:ff:fe:5d:62:23][listener.attribute_updated] Received an attribute update 3=9916 on cluster 768
[2021-08-08 11:00:43][INFO] : [cc:86:ec:ff:fe:5d:62:23][listener.attribute_updated] Received an attribute update 4=22446 on cluster 768
\x01\x00 B']
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
\x01\x00 B']
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
\x01\x00 B']
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
\x01\x00 B']
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
\x01\x00 B']
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:59:6e:47][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73bee330> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=60 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:59:6e:47][listener.attribute_updated] Received an attribute update 1=66 on cluster 0

Et ensuite j’ai vu l’erreur à 11h02 suivis du premier call à 11h47 quand j’ai utilisé un bouton zigbee :

[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:33][ERROR] : CRC error in frame b'5e2fa73be17e' (b'3be1' != b'5a86')
[2021-08-08 11:02:33][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:47:18][ERROR] : [cc:86:ec:ff:fe:59:6e:47][zdevices.command] Command failed retry in 1s : ApplicationController is not running

Et je donne le debug au moment de l’erreur 11h02 et appel de 11h47 :

[2021-08-08 11:02:31][DEBUG] : Sending: b'8070787e'
[2021-08-08 11:02:31][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000101400100007180bcec8affff07187b0a01002042'
[2021-08-08 11:02:31][DEBUG] : Data frame: b'7e2eb1a9112a15b658944a24ab1593499c3fa71701449874fade1883fd7e1fe5268a7e'
[2021-08-08 11:02:31][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPT
ION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=113), 128, -68, 0x8aec, 255, 255, b'\x18{
\x01\x00 B']
[2021-08-08 11:02:31][DEBUG] : Sending: b'8070787e'
[2021-08-08 11:02:31][DEBUG] : [0x8aec:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=123 command
_id=Command.Report_Attributes>
[2021-08-08 11:02:31][DEBUG] : Data frame: b'7e2eb1a9112a15b658944a24ab1593499c3fa71701449874fade1883fd7e1fe5268a7e'
[2021-08-08 11:02:31][DEBUG] : [0x8aec:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]]
[2021-08-08 11:02:31][DEBUG] : Sending: b'8070787e'
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_CO
MMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:31][DEBUG] : Data frame: b'7e2eb1a9112a15b658944a24ab1593499c3fa71701449874fade1883fd7e1fe5268a7e'
[2021-08-08 11:02:31][DEBUG] : [0x8aec:1:0x0000] Attribute report received: app_version=66
[2021-08-08 11:02:31][DEBUG] : Sending: b'8070787e'
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:31][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:31][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000101400100007180bcec8affff07187b0a01002042'
[2021-08-08 11:02:31][DEBUG] : Data frame: b'062eb1a9112a15b658944a24ab1593499ce1876f20759874fade5f83fd7e1fe56c4d7e'
[2021-08-08 11:02:31][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPT
ION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=113), 128, -68, 0x8aec, 255, 255, b'\x18{
\x01\x00 B']
[2021-08-08 11:02:31][DEBUG] : Sending: b'8160597e'
[2021-08-08 11:02:31][DEBUG] : [0x8aec:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=123 command
_id=Command.Report_Attributes>
[2021-08-08 11:02:31][DEBUG] : [0x8aec:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]]
[2021-08-08 11:02:31][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_CO
MMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] Attribute report received: app_version=66
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:32][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000101400100007180bcec8affff07187b0a01002042'
[2021-08-08 11:02:32][DEBUG] : Sending: b'612f21a9512aa3b47e'
[2021-08-08 11:02:32][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPT
ION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=113), 128, -68, 0x8aec, 255, 255, b'\x18{
\x01\x00 B']
[2021-08-08 11:02:32][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=123 command
_id=Command.Report_Attributes>
[2021-08-08 11:02:32][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]]
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_CO
MMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] Attribute report received: app_version=66
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:32][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'00040100000101400100007180bcec8affff07187b0a01002042'
[2021-08-08 11:02:32][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66))
[2021-08-08 11:02:32][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=113), 128, -68, 0x8aec, 255, 255, b'\x18{
\x01\x00 B']
[2021-08-08 11:02:32][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes>
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]]
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73db28b0> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=123 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.general_command] Ignoring already received this command last tsn=123 received 123
[2021-08-08 11:02:32][DEBUG] : [0x8aec:1:0x0000] Attribute report received: app_version=66
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:5d:5e:9c][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][DEBUG] : Received _reset_controller_application frame with (<NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT: 81>,)
[2021-08-08 11:02:32][DEBUG] : Resetting ControllerApplication. Cause: 'NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT'
[2021-08-08 11:02:32][DEBUG] : Send to jeedom : {'devices': {'cc:86:ec:ff:fe:5d:5e:9c': {'1': {'0': {'1': {'value': '66', 'cluster_name': 'Basic'}}}}}}
[2021-08-08 11:02:32][DEBUG] : Closed serial connection
[2021-08-08 11:02:32][DEBUG] : Application frame 69 (incomingMessageHandler) received: b'0004010000010140010000afa0c4cdbbffff07183c0a01002042'
[2021-08-08 11:02:32][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-08-08 11:02:32][DEBUG] : Received incomingMessageHandler frame with [<EmberIncomingMessageType.INCOMING_UNICAST: 0>, EmberApsFrame(profileId=260, clusterId=0, sourceEndpoint=1, destinationEndpoint=1, options=<EmberApsOption.APS_OPTION_ENABLE_ROUTE_DISCOVERY|APS_OPTION_RETRY: 320>, groupId=0, sequence=175), 160, -60, 0xbbcd, 255, 255, b'\x18<
\x01\x00 B']
[2021-08-08 11:02:32][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66)) completed
[2021-08-08 11:02:32][DEBUG] : [0xbbcd:1:0x0000] ZCL deserialize: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=True disable_default_response=True> manufacturer=None tsn=60 command_id=Command.Report_Attributes>
[2021-08-08 11:02:32][DEBUG] : [0xbbcd:1:0x0000] ZCL request 0x000a: [[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]]
[2021-08-08 11:02:32][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=N1lXfJXHKQZXvpW7OXx9i6WCI2rPeU2m HTTP/1.1" 200 0
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:59:6e:47][listener.general_command] Cluster: <zigpy.zcl.clusters.general.Basic object at 0x73bee330> cluster_id: 0x0000 command_id: <ZCLHeader frame_control=<FrameControl frame_type=GLOBAL_COMMAND manufacturer_specific=False is_reply=False disable_default_response=True> manufacturer=None tsn=60 command_id=Command.Report_Attributes> args: ([[Attribute(attrid=1, value=<TypeValue type=uint8_t, value=66>)]],)
[2021-08-08 11:02:32][DEBUG] : [0xbbcd:1:0x0000] Attribute report received: app_version=66
[2021-08-08 11:02:32][INFO] : [cc:86:ec:ff:fe:59:6e:47][listener.attribute_updated] Received an attribute update 1=66 on cluster 0
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][DEBUG] : Received _reset_controller_application frame with (<NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT: 81>,)
[2021-08-08 11:02:32][DEBUG] : Resetting ControllerApplication. Cause: 'NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT'
[2021-08-08 11:02:32][DEBUG] : Preempting ControllerApplication reset
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][DEBUG] : Received _reset_controller_application frame with (<NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT: 81>,)
[2021-08-08 11:02:32][DEBUG] : Resetting ControllerApplication. Cause: 'NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT'
[2021-08-08 11:02:32][DEBUG] : Preempting ControllerApplication reset
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][DEBUG] : Received _reset_controller_application frame with (<NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT: 81>,)
[2021-08-08 11:02:32][DEBUG] : Resetting ControllerApplication. Cause: 'NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT'
[2021-08-08 11:02:32][DEBUG] : Preempting ControllerApplication reset
[2021-08-08 11:02:32][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:32][DEBUG] : Received _reset_controller_application frame with (<NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT: 81>,)
[2021-08-08 11:02:32][DEBUG] : Resetting ControllerApplication. Cause: 'NcpResetCode.ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT'
[2021-08-08 11:02:32][DEBUG] : Preempting ControllerApplication reset
[2021-08-08 11:02:32][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>)
[2021-08-08 11:02:32][DEBUG] : Send to jeedom : {'devices': {'cc:86:ec:ff:fe:59:6e:47': {'1': {'0': {'1': {'value': '66', 'cluster_name': 'Basic'}}}}}}
[2021-08-08 11:02:32][DEBUG] : Starting new HTTP connection (1): 127.0.0.1:80
[2021-08-08 11:02:33][DEBUG] : http://127.0.0.1:80 "POST /plugins/zigbee/core/php/jeeZigbee.php?apikey=N1lXfJXHKQZXvpW7OXx9i6WCI2rPeU2m HTTP/1.1" 200 0
[2021-08-08 11:02:33][DEBUG] : Using selector: EpollSelector
[2021-08-08 11:02:33][ERROR] : CRC error in frame b'5e2fa73be17e' (b'3be1' != b'5a86')
[2021-08-08 11:02:33][DEBUG] : Sending: b'a0547d3a7e'
[2021-08-08 11:02:33][DEBUG] : Resetting EZSP
[2021-08-08 11:02:33][DEBUG] : Resetting ASH
[2021-08-08 11:02:33][DEBUG] : Sending: b'1ac038bc7e'
[2021-08-08 11:02:33][DEBUG] : Error code: ERROR_EXCEEDED_MAXIMUM_ACK_TIMEOUT_COUNT, Version: 2, frame: b'c20251a8bd7e'
[2021-08-08 11:02:33][ERROR] : NCP entered failed state. Requesting APP controller restart
[2021-08-08 11:02:33][DEBUG] : Closed serial connection
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66))
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66)) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>)
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66))
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66)) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>)
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66))
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:5d:5e:9c, 1, 0, 1, 66)) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>)
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:59:6e:47, 1, 0, 1, 66))
[2021-08-08 11:02:33][DEBUG] : operation functools.partial(<built-in method execute of sqlite3.Connection object at 0x73d73320>, 'INSERT OR REPLACE INTO attributes VALUES (?, ?, ?, ?, ?)', (cc:86:ec:ff:fe:59:6e:47, 1, 0, 1, 66)) completed
[2021-08-08 11:02:33][DEBUG] : executing functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>)
[2021-08-08 11:02:34][DEBUG] : operation functools.partial(<built-in method commit of sqlite3.Connection object at 0x73d73320>) completed
[2021-08-08 11:47:18][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': 'cc:86:ec:ff:fe:59:6e:47', 'cmd': [{'endpoint': 1, 'cluster': 'light_color', 'command': 'move_to_color', 'await': 1, 'args': [0, 0, 0]}], 'allowQueue': False}
[2021-08-08 11:47:18][ERROR] : [cc:86:ec:ff:fe:59:6e:47][zdevices.command] Command failed retry in 1s : ApplicationController is not running
[2021-08-08 11:47:18][DEBUG] : [GroupHandler.prepare] Json arg : {'ieee': '2', 'cmd': [{'endpoint': 0, 'cluster': 'light_color', 'command': 'move_to_color', 'await': 1, 'args': [0, 0, 0]}], 'allowQueue': False}
[2021-08-08 11:47:18][DEBUG] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 362, in put
    await zgroups.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zgroups.py", line 77, in command
    await command(*args)
  File "/usr/local/lib/python3.7/dist-packages/zigpy/group.py", line 50, in request
    data,
  File "/usr/local/lib/python3.7/dist-packages/bellows/zigbee/application.py", line 385, in mrequest
    raise ControllerError("ApplicationController is not running")
bellows.exception.ControllerError: ApplicationController is not running

[2021-08-08 11:47:18][INFO] : 200 PUT /group/command (127.0.0.1) 613.54ms
[2021-08-08 11:47:19][DEBUG] : [GroupHandler.prepare] Json arg : {'ieee': '2', 'cmd': [{'endpoint': 0, 'cluster': 'on_off', 'command': 'off', 'await': 1}], 'allowQueue': False}
[2021-08-08 11:47:19][DEBUG] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 362, in put
    await zgroups.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zgroups.py", line 82, in command
    await command()
  File "/usr/local/lib/python3.7/dist-packages/zigpy/group.py", line 50, in request
    data,
  File "/usr/local/lib/python3.7/dist-packages/bellows/zigbee/application.py", line 385, in mrequest
    raise ControllerError("ApplicationController is not running")
bellows.exception.ControllerError: ApplicationController is not running

[2021-08-08 11:47:19][INFO] : 200 PUT /group/command (127.0.0.1) 11.38ms
[2021-08-08 11:47:19][DEBUG] : Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 66, in command
    await command(*args)
  File "/usr/local/lib/python3.7/dist-packages/zigpy/device.py", line 278, in request
    use_ieee=use_ieee,
  File "/usr/local/lib/python3.7/dist-packages/bellows/zigbee/application.py", line 434, in request
    raise ControllerError("ApplicationController is not running")
bellows.exception.ControllerError: ApplicationController is not running

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/var/www/html/plugins/zigbee/resources/zigbeed/restServer.py", line 253, in put
    await zdevices.command(self.json_args)
  File "/var/www/html/plugins/zigbee/resources/zigbeed/zdevices.py", line 70, in command
    await command(*args)
  File "/usr/local/lib/python3.7/dist-packages/zigpy/device.py", line 278, in request
    use_ieee=use_ieee,
  File "/usr/local/lib/python3.7/dist-packages/bellows/zigbee/application.py", line 434, in request
    raise ControllerError("ApplicationController is not running")
bellows.exception.ControllerError: ApplicationController is not running

[2021-08-08 11:47:19][INFO] : 200 PUT /device/command (127.0.0.1) 1133.60ms
[2021-08-08 11:47:19][DEBUG] : [DeviceHandler.prepare] Json arg : {'ieee': 'cc:86:ec:ff:fe:59:6e:47', 'cmd': [{'endpoint': 1, 'cluster': 'level', 'command': 'move_to_level', 'await': 1, 'args': [0, 0]}], 'allowQueue': False}
[2021-08-08 11:47:19][ERROR] : [cc:86:ec:ff:fe:59:6e:47][zdevices.command] Command failed retry in 1s : ApplicationController is not running

J’ai aucun pic cpu/mem à ce moment, pas de souci d’espace disque, pas de scénario spécifique non plus.

Quelqu’un d’autre à la souci et à su le résoudre ?