Dashboard - Evolutions / Bugs / etc

lmilcent · Février 1, 2022, 3:27

L’interface web de Gladys met régulièrement beauuucoup de temps à charger chez moi, sans raisons apparentes.

htop normal, peu de ressources utilisées
iotop idem (équivalent htop niveau disque, je suis sur carte SD en lecture pour le système pour la préserver)
docker stats normal, tout est autour de 0% à 10%
réseau : le service web de Zigbee2MQtt se charge en moins de 1 seconde (sur le Rpi aussi)
Log Gladys, RAS.

Niveau navigateur (Chrome) on peut voir une des requêtes à 57 secondes et d’autres à 5 secondes !
J’ai seulement chargé la page d’accueil de mon instance.

Autres bugs, liés à celui-ci :

Parfois au chargement l’onglet “Scene” disparait le temps des lenteurs
Même lenteur sur l’onglet “Integration”

Est-ce possible que ma DB soit trop grosse et ralentisse la réponse aux requêtes ?

pierre-gilles · Février 1, 2022, 3:54

57 secondes sur un get myself, c’est clairement pas normal ! Cette requête devrait mettre moins de 100ms.

Est-ce que l’agrégation ne serait pas en cours sur ton instance? Ou la sauvegarde Gladys Plus ?

Il doit y avoir quelque chose qui bloque

lmilcent · Février 1, 2022, 3:57

C’est bien ce que je me suis dit, mais je n’arrive pas à trouver ce que ça peut être. Et vu que je m’en rend compte régulièrement, je pense pas que ça soit la sauvegarde ou l’aggrégation qui prennent que quelques minutes.
Je suis en train de supprimer quelques valeurs de ma DB, mais vu le temps que ça met je soupçonne une vitesse d’écriture très basse sur le Rpi. Ce serait une défaillance de carte SD déjà ?

Edit : pendant la commande de suppression de données de ma DB, mes commandes en SSH prennent littéralement des secondes voire des minutes. Mais ça travaille pas mal sur la SD, avec 10Mo/s.

Edit 2 : Une fois les conteneurs à 0%, j’ai supprimé un device depuis Gladys et là c’est monté à 20% pendant 30 secondes et ma requête web à mis presque 2 minutes à s’exécuter :

On voit clairement que c’est Gladys qui génère beaucoup de lecture et d’écriture. Comme si elle aimait pas trop ma DB à 3.5Go

VonOx · Février 1, 2022, 4:31

J’ai ce constat depuis quelque temps aussi, c’est “lent”. Malheureusement je n’ai pas eu le temps d’analyser.

C’est juste du ressenti mais via Gladys plus c’est pire (sachant que le facteur réseau joue aussi).

Niveau hardware j’ai largement de quoi ( hdd velociraptor / 24gb de ram / 8 core), y’a du lock quelque part.

lmilcent · Février 1, 2022, 4:33

J’ai trouvé une erreur dans le service Zigbee2MQTT. On dirait qu’une valeur non gérée à fait planter le service (error).

@cicoub13 tu aurais une idée de quoi chercher ?

VonOx · Février 1, 2022, 4:34

Tu sais sur quel device ?

lmilcent · Février 1, 2022, 4:37

Non pas du tout. Même erreur au redémarrage.

Depuis j’ai redémarré Gladys et les lenteurs ont disparues @pierre-gilles.

lmilcent · Février 1, 2022, 4:57

Maintenant que j’y pense, j’ai vu mes têtes MOES thermostatiques de radiateur avec cette feature quand je les ai ajouté à Gladys. A mon avis c’est “à cause” d’elles.

lmilcent · Février 2, 2022, 9:53

@cicoub13 ou @pierre-gilles , savez vous ce qui se passe quand je supprimer un équipement de la liste dans le service Zigbee ?
Est-ce que toutes ses données associées sont supprimées de la base de données ?

Cela expliquerait pourquoi lorsque je supprime un équipement, la suppression patauge quelques minutes avant de terminer.

[EDIT]
Je confirme, ça fait maintenant facilement 30 minutes que la suppression d’un capteur est en cours. Je ne suis pas étonné car c’est celui qui pollue le plus. Mais c’est un vrai problème car mon instance Gladys ne répond plus du tout pendant ce temps.
Mes scène ne fonctionnent plus pour le moment, je dois attendre que l’opération se termine.

pierre-gilles · Février 2, 2022, 5:16

Si tu es sur une carte SD, pas étonnant, ça plafonne à 10mo/s et ensuite il faut juste attendre que tout se « dépile », sauf que si ça empile plus vite que ce que le disque est capable de dépiler, bah ça bouchonne, et seul un restart peut vider la « pile »

ça par contre c’est plus étonnant vu ton hardware! Il faudrait comprendre ce qui bloque. Tu as combien de device et de quelles intégrations ? Il faut analyser pour trouver ce qui ralentit.

Je suis intéressé parce que pour le coup si il y a des endroits côté Gladys qu’on peut mieux optimiser, ça profitera à tout le monde

C’est une erreur pour indiquer qu’on gère pas cet event dans l’intégration Zigbee2mqtt, mais je ne pense pas que ça ait un lien avec les ralentissements ici

Oui !

Effectivement, si la DB est bloquée tout est bloqué (et chez toi la DB est le vrai facteur limitant avec ta SD qui est plafonnée à 10 mo/s)

En lisant vos différents messages, je ne pense pas qu’il y ait un problème unique, il y a peut-être différentes pistes à explorer.

@lmilcent de ton côté, pour moi le problème est plutôt clair: tu as beaucoup d’évènements Zigbee2mqtt qui sont envoyé vers Gladys, et ton disque a un débit plus faible que ce que Zigbee2mqtt envoie, du coup ça s’empile et plus ça s’empile et plus ton UI va être lente. Si tu redémarre Gladys ça résout le soucis temporairement car ça vide la pile, mais bon au bout de quelques jours à mon avis ça va revenir

Là dessus, plusieurs pistes:

Soit tu augmente le débit de ton disque (passer à un SSD)
Soit tu diminue la quantité de donnée écrite vers le disque:
- Soit voir côté Zigbee2mqtt si des appareils peuvent être moins verbose
- Soit côté Gladys on a beaucoup parlé d’un développement, ce serait pouvoir choisir par deviceFeature si on enregistre ou pas l’historique, ce qui permettrait d’améliorer les performance disques.

L’autre piste que je verrais, c’est le code de vérification des triggers de scènes.

@lmilcent si je me rappelle bien tu as beaucoup de scènes. Dans Gladys, chaque envoie de nouvel état de capteurs va lancer une vérification de chaque trigger de chaque scène pour voir si un trigger ne répond pas à la nouvelle valeur. Ce code pourrait prendre beaucoup de temps et créer de la « backpressure » côté Node.js. Après ça se traduirait par un usage CPU élevé ce qu’on ne voit pas ici donc rien de sûr… Je penche plus pour le disque ici

lmilcent · Février 2, 2022, 10:47

Merci pour ton analyse.

Limitation d’écriture sur le disque

Je suis étonné, car il ne s’agit pas d’un problème qui arrive plusieurs fois par jours, que je résoud temporairement en redémarrant uniquement.

Depuis ce matin, le disque est très très peu sollicité avec 50ko / sec en moyenne, loin des 10mo de ce matin pendant le problème. Il y a d’ailleurs eu des aggrégations depuis, sans problème particulier.

De plus j’ai éteins le capteur de CO2 qui avait l’air de polluer énormément.

Pour moi, la piste du disque saturé en écriture n’est pas la bonne pour expliquer tout ça.

Bon, après j’y gagnerais pas mal a passer sur disque ou ssd, mais mon cas reste intéressant pour tous ceux qui veulent ou peuvent pas le faire.

Base de donnée bloquée

C’est assez intéressant pour le coup. Pendant les grosses opérations, comme la supression de plusieurs milliers de données en base de données, c’est vrai que je me demande à ce moment là si le disque n’est pas saturé.

Mais pour le coup, le verrouillage de la DB est il nécessaire ? Est-ce inévitable où on peut faire des requêtes en parallèle pour ne pas bloquer Gladys ?
Car ne plus pouvoir utiliser Gladys dans ce genre de cas, c’est un peu dommage. Plus rien ne fonctionne, les scènes, la configuration, les dashboards, etc.

J’imagine que dans quelques années je ne serais pas le seul à avoir autant de données (2.5go a 3go environ).

D’ailleurs, est-il possible de faire tourner la nuit une sorte de maintenance de performance de la base ?
Par exemple, vérifier si des données sont orphelines et les supprimer (celles dont aucun capteur n’est associé) ?

zigbee

J’ai vu plusieurs événements de Gladys avertissant que la connexion mqtt était perdue, puis retrouvée, puis perdue, etc.
Depuis le redémarrage c’est de nouveau revenu à la normale.

Cependant encore maintenant, Gladys ne reçoit pas les événements de mes équipements et n’exécute plus les scènes associés. Pourtant l’interface de Zigbee2Mqtt me permet encore de contrôler mes équipements.

lmilcent · Février 3, 2022, 6:28

Ce matin, même problème.

Symptômes

mes scènes ne fonctionnent pas
zigbee2mqtt contrôle encore les équipements

Logs Gladys

Erreur de connexion mqtt en boucle.

Ressources cpu, ram, disk

Tout est normal d’après Netdata. Le pic a 00:00 correspond à ma sauvegarde Restic vers backblaze.

disque

Screenshot_20220203-0722203120×1440 252 KB
cpu

Screenshot_20220203-0721531920×886 72.3 KB
cpu du conteneur Gladys (on voit bien l’aggrégation)

Screenshot_20220203-0720213120×1440 224 KB

Résolution temporaire

Redémarrer Gladys.

damalgos · Février 3, 2022, 7:49

Hello,

Je rebondis sur le sujet notamment de la BDD. Si des opérations de delete sont trop volumineuses et couteuse. Pourquoi pas faire plusieurs delete ? On divise l’opération si il y a un certain nombre de ligne ? Au boulot on fait ça pour supprimer des entrées sinon on bloque le site complet. Y a-t-il des améliorations à faire côté BDD avec des index ? On fait du delete sur quelque chose qui est ptet pas indexé ? Ca peut être un soucis.

Pour la question de la carte SD je trouve ça un peu étrange comme approche @pierre-gilles , la V4 avait pour vocation de toujours tourner sur raspberry pi est d’être puissante et rapide. Le discours du coup est pas le même si on doit prendre un ssd etc.
C’est un peu une solution “pompier” qui reste légitime, pour le coupj’ai aucun problème sur mon instance mais je pense que Gladys devrait fonctionner correctement sur un raspberry avec une carte SD.

Surement des nouveaux besoins, des utilisations différentes, il doit y avoir pas mal d’optimisation à faire à mon avis.

lmilcent · Février 3, 2022, 8:15

Ajout des logs réseau

Mon hypothèse de départ pourrait se confirmer @pierre-gilles et @cicoub13. On dirait que l’erreur “non géré” fait planter le service zigbee2mqtt, contrairement à d’habitude, ce n’est pas un log d’avertissement mais un plantage nodejs.

Démarrage de Gladys : 07:07:44
Première erreur : 07:36:35
Depuis, plus rien ne fonctionne à nouveau.

Premiers logs d'erreur

2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating not configured in Gladys.
2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:36:35+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:36:35+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at Writable.writable._write (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:335:5)
    at doWrite (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:409:139)
    at writeOrBuffer (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:398:5)
    at Writable.write (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:307:11)
    at Socket.ondata (internal/streams/readable.js:731:22)
    at Socket.emit (events.js:400:28)
    at addChunk (internal/streams/readable.js:293:12)
    at readableAddChunk (internal/streams/readable.js:267:9)
    at Socket.Readable.push (internal/streams/readable.js:206:10)
    at TCP.onStreamRead (internal/stream_base_commons.js:188:23)
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar [redacted]@gmail.com
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar [redacted]@gmail.com
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar Contacts
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar PERSO
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar Jours fériés en France
2022-02-03T07:37:48+0100 <info> calendar.syncUserCalendars.js:38 (CalDAVHandler.syncUserCalendars) CalDAV : Found 5 calendars.
2022-02-03T07:38:11+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:38:16+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:38:16+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating not configured in Gladys.
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:38:16+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:38:16+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at processTicksAndRejections (internal/process/task_queues.js:77:11)
2022-02-03T07:40:16+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:40:21+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:40:21+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#

Logs Gladys de 7h à 9h

> gladys-server@ start:prod /src/server
> cross-env NODE_ENV=production node index.js

2022-02-03T07:07:44+0100 <info> job.purge.js:17 (Job.purge) Deleting all background jobs created before = Thu Jan 27 2022 07:07:44 GMT+0100 (Central European Standard Time)
Initialising OpenZWave 1.6.0 binary addon for Node.JS.
        OpenZWave Security API is ENABLED
        ZWave device db    : /usr/local/etc/openzwave
        User settings path : /src/server/services/zwave/node_modules/openzwave-shared/build/Release/../../
        Option Overrides : --Logging false --ConsoleOutput false --SaveConfiguration true
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service example was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service rtsp-camera was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service xiaomi was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service zwave was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service tasmota was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service bluetooth was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service ewelink was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service tp-link was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service google-actions was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> service.startAll.js:30 () Service philips-hue was manually stopped, so it is ignored at startup
2022-02-03T07:07:48+0100 <info> index.js:64 (Object.start) Starting CalDAV service
2022-02-03T07:07:48+0100 <info> index.js:20 (Object.start) Starting Open Weather service
2022-02-03T07:07:48+0100 <info> index.js:19 (Object.start) Starting Telegram service
2022-02-03T07:07:48+0100 <info> init.js:47 () Zigbee2mqtt USB dongle attached to /dev/ttyUSB0
2022-02-03T07:07:48+0100 <info> installMqttContainer.js:103 (Zigbee2mqttManager.installMqttContainer) MQTT broker container successfully started
2022-02-03T07:07:48+0100 <info> installZ2mContainer.js:71 (Zigbee2mqttManager.installZ2mContainer) Zigbee2mqtt container successfully started
2022-02-03T07:07:48+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:07:48+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:07:48+0100 <warn> handleMqttMessage.js:100 (Zigbee2mqttManager.handleMqttMessage) Zigbee2mqtt device RadiateurChEnfants not configured in Gladys.
2022-02-03T07:07:48+0100 <warn> handleMqttMessage.js:100 (Zigbee2mqttManager.handleMqttMessage) Zigbee2mqtt device RadiateurChParents not configured in Gladys.
2022-02-03T07:07:48+0100 <info> index.js:13 (Object.start) Starting usb service
2022-02-03T07:07:48+0100 <info> index.js:16 (Object.start) Starting MQTT service
2022-02-03T07:07:48+0100 <info> updateContainer.js:13 (MqttHandler.updateContainer) MQTT: checking for required changes...
2022-02-03T07:07:49+0100 <info> connect.js:38 (MqttClient.<anonymous>) Connected to MQTT server mqtt://localhost
2022-02-03T07:07:49+0100 <info> subscribe.js:12 (MqttHandler.subscribe) Subscribing to MQTT topic gladys/master/#
2022-02-03T07:07:49+0100 <info> scene.dailyUpdate.js:58 () Sunrise today is at 8:20 today, in your timezone = Europe/Paris
2022-02-03T07:07:49+0100 <info> scene.dailyUpdate.js:59 () Sunset today is at 17:51 today, in your timezone = Europe/Paris
2022-02-03T07:07:49+0100 <info> scene.dailyUpdate.js:67 () Sunrise is scheduled, in an hour.
2022-02-03T07:07:49+0100 <info> scene.dailyUpdate.js:81 () Sunset is scheduled, in 11 hours.
2022-02-03T07:07:49+0100 <info> device.calculateAggregate.js:38 (DeviceManager.calculateAggregate) Calculating aggregates device feature state for interval hourly
2022-02-03T07:07:49+0100 <info> index.js:63 (Server.<anonymous>) Server listening on port 80
2022-02-03T07:07:50+0100 <info> index.js:801 (Socket.<anonymous>) Gladys Gateway: connected in websockets
2022-02-03T07:07:50+0100 <info> gateway.checkIfBackupNeeded.js:27 (Gateway.checkIfBackupNeeded) Not backing up instance to Gladys Gateway, last backup is recent.
2022-02-03T07:07:53+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementEntree, feature illuminance_lux not configured in Gladys.
2022-02-03T07:07:53+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementEntree, feature linkquality not configured in Gladys.
2022-02-03T07:08:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature linkquality not configured in Gladys.
2022-02-03T07:08:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature power_outage_memory not configured in Gladys.
2022-02-03T07:08:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:08:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:08:04+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device InterrupteurSansFil, feature linkquality not configured in Gladys.
2022-02-03T07:08:05+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature linkquality not configured in Gladys.
2022-02-03T07:08:05+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature power_on_behavior not configured in Gladys.
2022-02-03T07:08:05+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update not configured in Gladys.
2022-02-03T07:08:05+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update_available not configured in Gladys.
2022-02-03T07:08:08+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device InterrupteurSansFil, feature linkquality not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:100 (Zigbee2mqttManager.handleMqttMessage) Zigbee2mqtt device guirlandes not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature linkquality not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature power_on_behavior not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update_available not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature linkquality not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature power_on_behavior not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update not configured in Gladys.
2022-02-03T07:08:09+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update_available not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device InterrupteurSansFil, feature linkquality not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:100 (Zigbee2mqttManager.handleMqttMessage) Zigbee2mqtt device guirlandes not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature linkquality not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature power_on_behavior not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update_available not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature linkquality not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature power_on_behavior not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update not configured in Gladys.
2022-02-03T07:08:11+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSalon, feature update_available not configured in Gladys.

[same logs for minutes - I have to reduce the amount of text for discourse to accept my message]

2022-02-03T07:33:17+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempCongel, feature linkquality not configured in Gladys.
2022-02-03T07:33:17+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempCongel, feature linkquality not configured in Gladys.
2022-02-03T07:33:17+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempCongel, feature linkquality not configured in Gladys.
2022-02-03T07:33:32+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature illuminance_lux not configured in Gladys.
2022-02-03T07:33:32+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature linkquality not configured in Gladys.
2022-02-03T07:33:32+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature illuminance_lux not configured in Gladys.
2022-02-03T07:33:32+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature linkquality not configured in Gladys.
2022-02-03T07:33:41+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:33:41+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature linkquality not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature power_outage_memory not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature linkquality not configured in Gladys.
2022-02-03T07:34:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature power_outage_memory not configured in Gladys.
2022-02-03T07:34:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempFrigo, feature linkquality not configured in Gladys.
2022-02-03T07:34:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempFrigo, feature linkquality not configured in Gladys.
2022-02-03T07:34:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurTempFrigo, feature linkquality not configured in Gladys.
2022-02-03T07:35:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature linkquality not configured in Gladys.
2022-02-03T07:35:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature power_outage_memory not configured in Gladys.
2022-02-03T07:35:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:35:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:35:02+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature illuminance_lux not configured in Gladys.
2022-02-03T07:35:02+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device CapteurMouvementSalleAManger, feature linkquality not configured in Gladys.
2022-02-03T07:36:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature linkquality not configured in Gladys.
2022-02-03T07:36:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseBureau, feature power_outage_memory not configured in Gladys.
2022-02-03T07:36:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:36:01+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:36:24+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature linkquality not configured in Gladys.
2022-02-03T07:36:24+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device PriseSupp, feature power_outage_memory not configured in Gladys.
2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating not configured in Gladys.
2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:36:35+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurSalleaManger, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:36:35+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:36:35+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at Writable.writable._write (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:335:5)
    at doWrite (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:409:139)
    at writeOrBuffer (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:398:5)
    at Writable.write (/src/server/services/zigbee2mqtt/node_modules/readable-stream/lib/_stream_writable.js:307:11)
    at Socket.ondata (internal/streams/readable.js:731:22)
    at Socket.emit (events.js:400:28)
    at addChunk (internal/streams/readable.js:293:12)
    at readableAddChunk (internal/streams/readable.js:267:9)
    at Socket.Readable.push (internal/streams/readable.js:206:10)
    at TCP.onStreamRead (internal/stream_base_commons.js:188:23)
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar [redacted]@gmail.com
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar [redacted]@gmail.com
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar Contacts
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar PERSO
2022-02-03T07:37:48+0100 <info> calendar.requests.js:52 () CalDAV : Found calendar Jours fériés en France
2022-02-03T07:37:48+0100 <info> calendar.syncUserCalendars.js:38 (CalDAVHandler.syncUserCalendars) CalDAV : Found 5 calendars.
2022-02-03T07:38:11+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:38:16+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:38:16+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating not configured in Gladys.
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:38:16+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:38:16+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:38:16+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at processTicksAndRejections (internal/process/task_queues.js:77:11)
2022-02-03T07:40:16+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:40:21+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:40:21+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:40:21+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating not configured in Gladys.
2022-02-03T07:40:21+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:40:21+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:40:21+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:40:21+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at processTicksAndRejections (internal/process/task_queues.js:77:11)
2022-02-03T07:42:21+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:42:26+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:42:26+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:42:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating not configured in Gladys.
2022-02-03T07:42:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:42:26+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:42:26+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:42:26+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at processTicksAndRejections (internal/process/task_queues.js:77:11)
2022-02-03T07:44:26+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server
2022-02-03T07:44:31+0100 <info> connect.js:24 (MqttClient.<anonymous>) Connected to MQTT container mqtt://localhost:1884
2022-02-03T07:44:31+0100 <info> subscribe.js:12 (Zigbee2mqttManager.subscribe) Subscribing to MQTT topic zigbee2mqtt/#
2022-02-03T07:44:31+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating not configured in Gladys.
2022-02-03T07:44:31+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown not configured in Gladys.
2022-02-03T07:44:31+0100 <warn> handleMqttMessage.js:96 () Zigbee2mqtt device RadiateurChEnfants, feature boost_heating_countdown_time_set not configured in Gladys.
2022-02-03T07:44:31+0100 <error> index.js:20 (process.<anonymous>) uncaughtException catched: uncaughtException
2022-02-03T07:44:31+0100 <error> index.js:21 (process.<anonymous>) Error: Zigbee2mqqt don't handle value "LOCK" for feature "mode".
    at convertValue (/src/server/services/zigbee2mqtt/utils/convertValue.js:55:17)
    at /src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:92:24
    at Array.forEach (<anonymous>)
    at Zigbee2mqttManager.handleMqttMessage (/src/server/services/zigbee2mqtt/lib/handleMqttMessage.js:86:41)
    at MqttClient.<anonymous> (/src/server/services/zigbee2mqtt/lib/connect.js:53:12)
    at MqttClient.emit (events.js:400:28)
    at MqttClient._handlePublish (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:1277:12)
    at MqttClient._handlePacket (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:410:12)
    at work (/src/server/services/zigbee2mqtt/node_modules/mqtt/lib/client.js:321:12)
    at processTicksAndRejections (internal/process/task_queues.js:77:11)
2022-02-03T07:46:31+0100 <warn> connect.js:44 (MqttClient.<anonymous>) Disconnected from MQTT server

cicoub13 · Février 3, 2022, 9:20

Je vais proposer un fix ce midi.

lmilcent · Février 3, 2022, 9:58

Tu confirmes qu’une erreur n’est pas gérée et fait planter le service ?

cicoub13 · Février 3, 2022, 12:07

Yes

PR à relire ici : https://github.com/GladysAssistant/Gladys/pull/1427

lmilcent · Février 3, 2022, 1:05

Merci pour ton fix ! Je teste ça sur mon instance gladys.

lmilcent · Février 3, 2022, 1:10

Gladys se lance et le service Zigbee fonctionne sans problème. Si d’ici ce soir Gladys fonctionne encore, c’est que le fix résout mon problème.

[EDIT] Gladys fonctionne toujours, le fix à résolu mon problème !

pierre-gilles · Février 3, 2022, 4:44

Beau travail @cicoub13 !

Je préfère que ce soit un problème comme ça plutôt qu’un souci de performance plus général. Après à voir si ça résout aussi les soucis chez @VonOx.

La PR de @cicoub13 était bonne pour moi, j’ai merge et j’ai lancé un build Docker sur le tag dev.

Je ne suis pas disponible ce week-end pour pouvoir surveiller une release, donc je ferais une release prod probablement en début de semaine prochaine