Bug jeedom et/ou jMQTT?

Hello a tous,

Je viens ici car je ne sais pas trop si cela mérite un post ailleurs… mais je viens de faire une découverte déconcertante…
Depuis plusieurs semaines, j’ai des latences aléatoires sur l’exécution de certains scénarios et changements d’états. Mon installation était initialement sur un RPI4 avec jeedom, jmqtt, zwave-js, zigbee2mqtt en mode all in one.

A la suite de ces soucis, je me suis penché sur la séparation des rôles:

  • Jeedom sur une VM qui tourne sur un ESXi avec quelques GB de mémoire, et 4 CPU (Xeon CPU E5-2690 v3 @ 2.60GHz…), mosquitto sur cette VM,
  • zwavejs et zigbee encore sur le RPI4 (sans changements, uniquement désactivation de mariadb et de apache), l’envoi des évènements MQTT passant maintenant par le réseau.

Résultat: toujours les mêmes problèmes… Je me tourne alors sur la topologie zwave/ zigbee depuis plusieurs jours, sans grand succès à coups de reinterview de tous les noeuds / heal du réseau etc etc…

En désespoir de cause, j’installe une VM sur le même serveur ESXi avec Home Assistant (même réseau), j’importe via mqtt les noeuds zwave / zigbee, je peux piloter de manière consistante les équipements au travers de Jeedom ET Home Assistant…

Je continue a troubleshooter, et j’essaye de faire un scénario sur Home assistant basé sur un capteur d’ouverture de porte Xiaomi qui allume une lampe en zwave… bref rien de très violent…
Et la surprise… le temps de réponse sur home assistant est instantané, ce qui n’est pas le cas sur Jeedom (avec même des évènements « rapides », ouvertures / fermetures, qui ne sont même pas détectés… alors que sous Home Assistant oui. Ce qui suppose que la remontée d’info semble ne pas se faire correctement).

Ma conclusion vient donc que le soucis proviendrait de Jeedom / jMQTT… est ce que quelqu’un aurait une idée d’où chercher? (j’ai deja mis un redis pour le caching)?

Je commence a sécher sérieusement :smiley:

Merci :wink:

Slt, très hâtive tes conclusions.
C’est sur que le moteur ha est plus rapide mais d’un côté tu as UN scénario et de l’autre on ne sait pas car tu n’as pas détaillé ton installe.:wink:

1 « J'aime »

:sweat_smile: j’ai essayé de synthétiser au maximum vu que la situation est déjà assez … complexe … :grinning: à la vue des tests que j’ai fait.

Certes côté ha, il n’y a pas grand chose niveau charge… juste le suivi historique etc reposant sur MQTT, plus quelques plugins. je ne vois pas comment je pourrais faire mieux hormis, refaire la même configuration de jeedom sur ha, mais ca me semble 1: compliqué, 2: ca n’apportera pas vraiment de valeur ajoutée.

Quels détails voudrais tu sur l’installation jeedom? le serveur tourne avec un load average de 0.12, le RPI est plus chargé car il héberge les node.js dis moi ce qu’il te semble pertinent, je me ferai un plaisir de résoudre ces soucis qui trainent depuis longtemps :sweat_smile:

Latence dans quel type d’action ?
Nombre de scénario,
Et surtout la taille de la base de donnée

Alors latence aléatoire:
→ ca peut être depuis l’interface web avec un allumage de lampe
→ le lancement d’un scenario qui effectue 4/5 commandes zigbee / zwave a la suite
→ les scénarios il y en a une 50 aine mais tous basé sur évènements, quasiment rien de programmé, et pas de concurrence entre eux:

La taille de la DB:

Merci :slight_smile:

Ta base est énorme, 800Mo, juste pour les archives et 20M de lignes. Surtout 6mo, 58000 lignes juste pour la journée courante (table History). Par sur que ce soit l’unique pb mais tu peux déjà commencer par ça :

Norbert

3 « J'aime »

Hello,

En effet ta bdd est assez grosse, il faudrait regarder pourquoi.

Dans la dernière beta de jMQTT j’ai ajouté des warnings supplémentaires si une commande mets trop longtemps à s’exécuter :

  • Ajout d’un alerte lorsqu’un message mets trop de temps à être traité par Jeedom (merci rennais35000)

Si tu es en beta, tu peux regarder les logs, sinon tu peux l’installer ou attendre que la beta passe en stable semaine prochaine.

Bad

Super merci, je lance le script et je regarde, c’est vrai que j’ai une facheuse tendance a historiser les choses /D

Concernant la charge systeme, je suis conscient que ce n’est pas optimal, mais je ne suis pas sur un RPI, c’est une VM sur un serveur bi CPU Xeon avec 4 vCPU, et 4G de RAM.
Mais néanmoins je vais regarder de ce coté.

Le resultat donne ca:

------------------------------------
[2023-04-01 17:21:24][SCENARIO] -- Start : Scenario lance manuellement.
[2023-04-01 17:21:24][SCENARIO] - Exécution du sous-élément de type [action] : code
[2023-04-01 17:21:24][SCENARIO] Exécution d'un bloc code 
[2023-04-01 17:21:24][SCENARIO] ┌──────────── Logs bloc code - version du 27/03/2023 19:00
[2023-04-01 17:21:24][SCENARIO] |
[2023-04-01 17:21:24][SCENARIO] | -------- RESUME
[2023-04-01 17:21:24][SCENARIO] |
[2023-04-01 17:21:24][SCENARIO] |     Table historyArch : Taille -> 820MB , nb lignes -> 20314012
[2023-04-01 17:21:24][SCENARIO] | /!\ Table Anormalement grosse, taille superieure au seuil (820MB)
[2023-04-01 17:21:24][SCENARIO] | /!\ Table Anormalement grosse, Nb de lignes superieur au seuil (20314012 lignes)
[2023-04-01 17:21:24][SCENARIO] |     Table timeline : Taille -> 18MB , nb lignes -> 66547
[2023-04-01 17:21:24][SCENARIO] |     Table history : Taille -> 5MB , nb lignes -> 31807
[2023-04-01 17:21:24][SCENARIO] |     Table cmd : Taille -> 3MB , nb lignes -> 4554
[2023-04-01 17:21:24][SCENARIO] |     Table eqLogic : Taille -> 2MB , nb lignes -> 362
[2023-04-01 17:21:24][SCENARIO] |
[2023-04-01 17:21:24][SCENARIO] | -------- FIN RESUME
[2023-04-01 17:21:24][SCENARIO] |
[2023-04-01 17:21:24][SCENARIO] | -------- COMMANDES AVEC DONNEES VOLUMINEUSES
[2023-04-01 17:21:24][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | virtual -> #[Appartement][Compteur Enovos][Consommation instantanée]# (id : 29490)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (13735) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (2360029), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 7077 valeurs (estimation sur 24h : 13735)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 2360029 valeurs depuis 214 jours (11028 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Abri Jardin Thionville][Abri Jardin][Température]# (id : 28313)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1747) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (1616098), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 900 valeurs (estimation sur 24h : 1747)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 1616098 valeurs depuis 413 jours (3913 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Abri Jardin Thionville][Abri Jardin][Humidité]# (id : 28314)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1745) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (1600720), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 899 valeurs (estimation sur 24h : 1745)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 1600720 valeurs depuis 413 jours (3876 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Cour arriere][LED Strip][Puissance]# (id : 28279)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (2791) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (1547725), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 1438 valeurs (estimation sur 24h : 2791)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 1547725 valeurs depuis 413 jours (3748 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Salon][Capteur Lumiere Z2M][Lux]# (id : 26751)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1461) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (1149729), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 753 valeurs (estimation sur 24h : 1461)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 1149729 valeurs depuis 510 jours (2254 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Salon][Capteur Lumiere Z2M][Luminosite]# (id : 26750)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1465) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (1148283), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 755 valeurs (estimation sur 24h : 1465)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 1148283 valeurs depuis 510 jours (2252 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Loggia][Sonde lumiere][Luminosite]# (id : 28334)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1405) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (992152), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 724 valeurs (estimation sur 24h : 1405)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 992152 valeurs depuis 385 jours (2577 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Loggia][Sonde lumiere][Lux]# (id : 28335)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (1411) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (986982), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 727 valeurs (estimation sur 24h : 1411)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 986982 valeurs depuis 385 jours (2564 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Salle de Bain][Capteur lumiere Z2M][Luminosite]# (id : 26969)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (982) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (933065), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 506 valeurs (estimation sur 24h : 982)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 933065 valeurs depuis 510 jours (1830 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | jMQTT -> #[Salle de Bain][Capteur lumiere Z2M][Lux]# (id : 26970)
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (967) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:21:58][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (928061), superieur à 50000
[2023-04-01 17:21:58][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:21:58][SCENARIO] |     Historique    : 498 valeurs (estimation sur 24h : 967)
[2023-04-01 17:21:58][SCENARIO] |     Archives      : 928061 valeurs depuis 510 jours (1820 valeurs par jour en moyenne)
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | -------- FIN COMMANDES AVEC DONNEES VOLUMINEUSES
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:21:58][SCENARIO] | -------- COMMANDES AVEC DONNEES ALPHANUMERIQUES
[2023-04-01 17:21:58][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Garage][Etat Porte Garage][Absence de Fermeture]# (id : 3975)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (3322857), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> -1 year
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 11585 caractères (estimation sur 24h : 22460)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 3322857 caractères depuis 364 jours (9129 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salon][Store Milieu][Mode]# (id : 22868)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (403) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (82738), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 208 caractères (estimation sur 24h : 403)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 82738 caractères depuis 669 jours (124 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salon][Store Droit][Mode]# (id : 22866)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (403) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (78442), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 208 caractères (estimation sur 24h : 403)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 78442 caractères depuis 669 jours (117 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salle de Bain][Store Gauche][Mode]# (id : 22870)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (140) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (75688), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 72 caractères (estimation sur 24h : 140)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 75688 caractères depuis 669 jours (113 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salle de Bain][Store Droit][Mode]# (id : 22864)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (140) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (75653), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 72 caractères (estimation sur 24h : 140)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 75653 caractères depuis 669 jours (113 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salon][Store Gauche][Mode]# (id : 22867)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (62270), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 0 caractères (estimation sur 24h : 0)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 62270 caractères depuis 669 jours (93 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salle a Manger][Store Gauche][Mode]# (id : 22861)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (85) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (57141), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 44 caractères (estimation sur 24h : 85)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 57141 caractères depuis 669 jours (85 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Bibliothèque][Store Droit][Mode]# (id : 22850)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (207) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur archivéess trop importantes (52883), superieur à 50000
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 107 caractères (estimation sur 24h : 207)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 52883 caractères depuis 669 jours (79 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Salle a Manger][Store Milieu][Mode]# (id : 22862)
[2023-04-01 17:22:45][SCENARIO] | /!\ PB DE PARAMETRAGE, nb de valeur par 24h important (54) et aucun lissage ni aucune purge paramétrés
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 28 caractères (estimation sur 24h : 54)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 48031 caractères depuis 669 jours (72 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | virtual -> #[Cuisine][Store Gauche][Mode]# (id : 22857)
[2023-04-01 17:22:45][SCENARIO] |     Configuration : historisé -> 1 , lissage -> aucun , purge -> aucun
[2023-04-01 17:22:45][SCENARIO] |     Historique    : 21 caractères (estimation sur 24h : 41)
[2023-04-01 17:22:45][SCENARIO] |     Archives      : 47923 caractères depuis 668 jours (72 caractères par jour en moyenne)
[2023-04-01 17:22:45][SCENARIO] |
[2023-04-01 17:22:45][SCENARIO] | -------- FIN COMMANDES AVEC DONNEES ALPHANUMERIQUES
[2023-04-01 17:22:45][SCENARIO] └───────────────────────────
[2023-04-01 17:22:45][SCENARIO] Fin correcte du scénario

Je vais investiguer de ce coté :wink:

Hello,

Super merci de l’info je vais passer en beta pour voir s’il y a des infos pertinentes.
Le niveau de log doit être a quel niveau pour voir les messages? Warning je suppose?

Merci.

Debug est le.mieux !

1 « J'aime »

Yes warning.
Un log sera écrit si une commande mets plus de 300ms à être traitée.

Alors premier retour,

J’ai divisé par 2 le nombre de données archivées, mais ce n’est pas encore tres probant.
En attendant, pas d’entrées de log jMQTT concernant des latences, et un DB qui est reduite a 400Mo mais j’ai encore un peu de boulot :wink:

Hello,

Avec des logs de jMQTT (eqBroker et démon) en debug, on doit pouvoir identifier plus précisément où est la latence.
Tu peux me fournir ça ou un accès à ton jeedom (en mp) ?

Bad

Yep je passe en mode debug mais j’ai pas mal d’event je préviens :slight_smile:
ma domotique, c’est 75 noeuds Zwave et 96 zigbee :slight_smile: donc le decodage risque de piquer un peu…

Pas de souci, j’ai l’habitude de traiter des fichiers de log volumineux.

Pour info, les fichiers sont tronqués tous les jours lors du nettoyage des logs par Jeedom (cronDaily @ ~3h35) ET lorsqu’ils sont ouvert en consultation. Pour éviter qu’il ne soit tronqués à l’ouverture et bien me transmettre tous les infos, il suffit de les télécharger au format archive avec l’éditeur de fichier :
image

Si le .zip ne passe pas par message privé, renomme-le en .zip.txt

Oui t’inquiete pas je te sors ca dans la soirée. La latence semble plutot sur le zwave que le zigbee mais c’est un ressenti, je n’ai pas réellement de « preuve ».

Pour identifier mes équipements zwave, le path du topic c’est… zwave :smiley: et pour zigbee je te laisse deviner.

J’envoie le fichier de log dans la soirée comme c’est plutôt erratique.

J’ai quand même une question de fond… pourquoi l’archivage des évènements aurait un tel impact sur le temps de réponse du core? lorsqu’on consulte les archive, je comprends, par contre quand on rajout une entrée dans la table, je ne comprends pas trop… surtout vu le pool de ressource disponible… Si quelqu’un peut éclairer ma lanterne :sweat_smile: je suis preneur

EDIT: voici le fichier en PJ :slight_smile: j’ai voulu attendre 22h, car fermeture des volets, remontée des stores, et passage en mode nuit des veilleuses de mes walli (gros burst de données a ce moment, le plus gros de la journée je pense) mais je n’ai pas ressenti de latence ce soir. Tiens moi au jus si tu vois quelque chose :wink:
jMQTTd.log (291,3 Ko)

Hello,

Merci pour les logs, mais il n’y a moins d’une minute de log (ce n’est pas très représentatif) et uniquement du daemon, pas de l’équipement Broker. Tu pourras me les envoyer en utilisant la méthode décrite précédemment pour éviter qu’ils ne soient tronqués ?

1 « J'aime »

erf je viens de voir ton message… je viens de relancer le démon en debug … je laisse tourner un peu, vers 22h comme hier il y a pas mal de chose qui vont se lancer. J’enverrai un peu plus tard

Edit: voila :slight_smile: j’ai laissé tourner, mais il y a deja 20 min :slight_smile: Je rajouterai demain soir le log de la journée :slight_smile:
jMQTTd.zip.txt (111,2 Ko)

Hello,

Il me faudra bien tous les logs de jMQTT (jMQTT*) et aussi le log event en debug, si tu l’as activé, et que tu fasses un test avec une commande qui lag en particulier (depuis Jeedom, puis depuis HA pour comparer les données reçues, et que tu me dises à quel moment tu as fait le test).

Car il n’y a pas beaucoup de matière dans ce log :

Léger engorgement à 22:15:17 (18 messages envoyés vs << 3 la plus part du temps) :

[2023-04-05 22:15:07,685][INFO] Client1093      Brk1093Th       on_message() : Message received [...]
...
[2023-04-05 22:15:07,782][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 18 msgs
...
[2023-04-05 22:15:07,939][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [...]

Mais on voit que Jeedom a TOUT traité en 157ms, pour un temps de traitement complet (entre réception du 1er des 18 messages par le daemon et la prise en compte par Jeedom) de 254ms, ce qui est assez canon. Habituellement les salves mettent plus de temps à être traitées, logique, car elles contiennent en moyenne plus de messages.

Par contre, dans la trace, je constate qu’une commande en particulier est exécutée AU MOINS une fois par minute, toujours avec le même payload, est-ce normal ? :

[2023-04-05 22:01:03,839][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:02:03,091][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:02:56,619][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:03:03,334][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:03:11,452][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:03:44,128][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:03:44,329][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:04:03,171][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:05:03,110][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:05:03,110][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:06:03,650][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:07:03,492][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:08:03,733][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:08:42,923][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:08:44,227][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:09:03,975][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:09:49,079][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:10:04,217][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:10:04,418][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:11:04,362][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:12:03,695][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:13:04,143][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:13:41,734][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:13:42,939][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:14:03,788][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:15:04,030][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:15:04,331][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:16:03,576][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:17:02,914][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)
[2023-04-05 22:18:03,267][INFO] Client1093      MainThread         publish() : Sending message to broker (topic="zigbee2mqtt/SAM - Store droit/set", payload="{"position":100}", QoS=1, retain=False)

J’attends la suite des logs pour aller plus dans le dur :wink:

Salut,

Merci pour le feedback :slight_smile:
Alors petite info, j’ai tenté hier de repasser l’instance jeedom sur le RPI4 pour avoir tout indépendant de mon infra serveur mais mauvaise idée … :smiley: c’est lent a mourir c’est pour ca que je n’ai pas envoyé les logs hier.

Je viens de rebasculer sur ma VM et ça va beaucoup mieux (le RPI continue d’avoir zigbee2mqtt et zwave-js).

Concernant l’execution sur le store, bien vu, je vais desactiver la gestion du store (c’est de l’IKEA qui arrete pas de tomber en panne, j’ai deja du changer je pense 15/16 stores… ) et dans tous les cas, le moteur est mort donc ca sert a rien :ok_hand:

Concernant les logs, je n’ai que deux fichiers de logs en debug: jmqttd et jmqtt_dep par contre…

EDIT: ok oublie l’event est global … je te fais ca :upside_down_face: