Problème jmqtt depuis la mise à jour

[JC38]

Bonjour,
Je reprends ce sujet pour faire part d’un pb depuis la mise à jour du 15.
Le plugin reboot très fréquemment depuis hier midi, heure d’installation de la version.
cf logs ci dessous. Je viens d’activer le mode debug, je posterai de nouveaux logs plus tard.

[2023-04-10 21:00:19][INFO] : Début d'activation du plugin
[2023-04-10 21:00:20][INFO] : Info sur le démon : {"launchable_message":"","launchable":"nok","state":"nok","log":"nok","auto":0}
[2023-04-10 21:00:31][INFO] : Installation des dépendances, voir log dédié (jMQTT_dep)
[2023-04-10 21:01:31][INFO] : Démarrage du démon jMQTT
[2023-04-10 21:01:32][INFO] : Définition de la clé API de jMQTT
[2023-04-10 21:01:32][INFO] : Lancement du démon jMQTT
[2023-04-10 21:01:32][INFO] : Démon démarré
[2023-04-11 06:40:02][INFO] : Démarrage du démon jMQTT
[2023-04-11 06:40:03][INFO] : Lancement du démon jMQTT
[2023-04-11 06:40:03][INFO] : Démon démarré
[2023-04-13 09:06:05][INFO] : Arrêt du démon jMQTT
[2023-04-13 09:06:05][INFO] : Démon jMQTT arrêté
[2023-04-13 09:10:20][INFO] : Démarrage du démon jMQTT
[2023-04-13 09:10:21][INFO] : Lancement du démon jMQTT
[2023-04-13 09:10:21][INFO] : Démon démarré
[2023-04-15 12:22:41][INFO] : Début d'activation du plugin
[2023-04-15 12:22:41][INFO] : Arrêt du démon jMQTT
[2023-04-15 12:22:42][INFO] : Démon jMQTT arrêté
[2023-04-15 12:22:43][INFO] : Info sur le démon : {"launchable":"ok","log":"jMQTT","state":"nok","launchable_message":"Gestion automatique d\u00e9sactiv\u00e9e","auto":"0","last_launch":"2023-04-13 09:10:20"}
[2023-04-15 12:22:43][INFO] : Commandes orphelines supprimées
[2023-04-15 12:23:01][INFO] : Installation/Vérification forcée des dépendances, le démon jMQTT démarrera au prochain essai
[2023-04-15 12:23:03][INFO] : Installation des dépendances, voir log dédié (jMQTT_dep)
[2023-04-15 12:25:03][INFO] : Démarrage du démon jMQTT
[2023-04-15 12:25:04][INFO] : Lancement du démon jMQTT
[2023-04-15 12:25:04][INFO] : Démon démarré
[2023-04-15 12:35:01][INFO] : Démarrage du démon jMQTT
[2023-04-15 12:35:02][INFO] : Lancement du démon jMQTT
[2023-04-15 12:35:02][INFO] : Démon démarré
[2023-04-15 13:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 13:20:03][INFO] : Lancement du démon jMQTT
[2023-04-15 13:20:03][INFO] : Démon démarré
[2023-04-15 13:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 13:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 13:35:03][INFO] : Démon démarré
[2023-04-15 14:35:01][INFO] : Démarrage du démon jMQTT
[2023-04-15 14:35:02][INFO] : Lancement du démon jMQTT
[2023-04-15 14:35:02][INFO] : Démon démarré
[2023-04-15 15:35:01][INFO] : Démarrage du démon jMQTT
[2023-04-15 15:35:02][INFO] : Lancement du démon jMQTT
[2023-04-15 15:35:02][INFO] : Démon démarré
[2023-04-15 16:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 16:20:03][INFO] : Lancement du démon jMQTT
[2023-04-15 16:20:03][INFO] : Démon démarré
[2023-04-15 16:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 16:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 16:35:03][INFO] : Démon démarré
[2023-04-15 16:55:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 16:55:03][INFO] : Lancement du démon jMQTT
[2023-04-15 16:55:03][INFO] : Démon démarré
[2023-04-15 17:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 17:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 17:35:03][INFO] : Démon démarré
[2023-04-15 17:55:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 17:55:03][INFO] : Lancement du démon jMQTT
[2023-04-15 17:55:03][INFO] : Démon démarré
[2023-04-15 18:07:57][INFO] : Sauvegarde de jMQTT lancée...
[2023-04-15 18:07:57][INFO] : ###########################################################
[2023-04-15 18:07:57][INFO] : Starting jMQTT backup...
[2023-04-15 18:07:57][INFO] : Writing PID file...                                  [ OK ]
[2023-04-15 18:07:57][INFO] : Cleaning up...                                       [ OK ]
[2023-04-15 18:07:57][INFO] : Preparing to backup...                               [ OK ]
[2023-04-15 18:07:57][INFO] : Generating index file...                             [ OK ]
[2023-04-15 18:07:57][INFO] : Generating Data file...                              [ OK ]
[2023-04-15 18:07:57][INFO] : Generating History file...                           [ OK ]
[2023-04-15 18:07:57][INFO] : Backing up jMQTT folder...                           [ OK ]
[2023-04-15 18:07:57][INFO] : Backing up jMQTT log files...                        [ OK ]
[2023-04-15 18:07:57][INFO] : Backing up Mosquitto config...                       [ OK ]
[2023-04-15 18:07:57][INFO] : Generating Metadata file...                          [ OK ]
[2023-04-15 18:07:57][INFO] : Creating archive jMQTT_20230415_180757.tgz...        [ OK ]
[2023-04-15 18:07:58][INFO] : Cleaning up...                                       [ OK ]
[2023-04-15 18:07:58][INFO] : Removing PID file...                                 [ OK ]
[2023-04-15 18:07:58][INFO] : End of jMQTT backup.
[2023-04-15 18:07:58][INFO] : ###########################################################
[2023-04-15 18:07:58][INFO] : Sauvegarde de jMQTT effectuée
[2023-04-15 19:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 19:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 19:35:03][INFO] : Démon démarré
[2023-04-15 20:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 20:20:03][INFO] : Lancement du démon jMQTT
[2023-04-15 20:20:03][INFO] : Démon démarré
[2023-04-15 20:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 20:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 20:35:03][INFO] : Démon démarré
[2023-04-15 21:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 21:35:02][INFO] : Lancement du démon jMQTT
[2023-04-15 21:35:03][INFO] : Démon démarré
[2023-04-15 22:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 22:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 22:35:03][INFO] : Démon démarré
[2023-04-15 23:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 23:35:03][INFO] : Lancement du démon jMQTT
[2023-04-15 23:35:03][INFO] : Démon démarré
[2023-04-15 23:55:02][INFO] : Démarrage du démon jMQTT
[2023-04-15 23:55:03][INFO] : Lancement du démon jMQTT
[2023-04-15 23:55:03][INFO] : Démon démarré
[2023-04-16 00:35:01][INFO] : Démarrage du démon jMQTT
[2023-04-16 00:35:02][INFO] : Lancement du démon jMQTT
[2023-04-16 00:35:02][INFO] : Démon démarré
[2023-04-16 01:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 01:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 01:20:03][INFO] : Démon démarré
[2023-04-16 01:35:01][INFO] : Démarrage du démon jMQTT
[2023-04-16 01:35:02][INFO] : Lancement du démon jMQTT
[2023-04-16 01:35:02][INFO] : Démon démarré
[2023-04-16 02:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 02:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 02:20:03][INFO] : Démon démarré
[2023-04-16 02:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 02:35:02][INFO] : Lancement du démon jMQTT
[2023-04-16 02:35:03][INFO] : Démon démarré
[2023-04-16 02:50:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 02:50:03][INFO] : Lancement du démon jMQTT
[2023-04-16 02:50:03][INFO] : Démon démarré
[2023-04-16 03:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 03:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 03:20:03][INFO] : Démon démarré
[2023-04-16 03:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 03:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 03:35:03][INFO] : Démon démarré
[2023-04-16 03:50:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 03:50:02][INFO] : Lancement du démon jMQTT
[2023-04-16 03:50:03][INFO] : Démon démarré
[2023-04-16 04:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 04:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 04:20:03][INFO] : Démon démarré
[2023-04-16 04:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 04:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 04:35:03][INFO] : Démon démarré
[2023-04-16 04:50:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 04:50:03][INFO] : Lancement du démon jMQTT
[2023-04-16 04:50:03][INFO] : Démon démarré
[2023-04-16 05:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 05:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 05:20:03][INFO] : Démon démarré
[2023-04-16 05:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 05:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 05:35:03][INFO] : Démon démarré
[2023-04-16 05:50:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 05:50:03][INFO] : Lancement du démon jMQTT
[2023-04-16 05:50:03][INFO] : Démon démarré
[2023-04-16 06:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 06:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 06:20:03][INFO] : Démon démarré
[2023-04-16 06:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 06:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 06:35:03][INFO] : Démon démarré
[2023-04-16 07:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 07:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 07:20:03][INFO] : Démon démarré
[2023-04-16 07:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 07:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 07:35:03][INFO] : Démon démarré
[2023-04-16 07:50:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 07:50:02][INFO] : Lancement du démon jMQTT
[2023-04-16 07:50:03][INFO] : Démon démarré
[2023-04-16 08:20:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 08:20:03][INFO] : Lancement du démon jMQTT
[2023-04-16 08:20:03][INFO] : Démon démarré
[2023-04-16 08:35:02][INFO] : Démarrage du démon jMQTT
[2023-04-16 08:35:03][INFO] : Lancement du démon jMQTT
[2023-04-16 08:35:03][INFO] : Démon démarré
[2023-04-16 08:50:01][INFO] : Démarrage du démon jMQTT
[2023-04-16 08:50:02][INFO] : Lancement du démon jMQTT
[2023-04-16 08:50:02][INFO] : Démon démarré
[2023-04-16 09:20:05][INFO] : Démarrage du démon jMQTT
[2023-04-16 09:20:06][INFO] : Lancement du démon jMQTT
[2023-04-16 09:20:06][INFO] : Démon démarré

Voici le log des dependances réinstallées hier : RAS

======================================================================
== 15/04/2023 12:23:03 == Dependencies installation for jMQTT
======================================================================

[  0% ] : Checking parameters...
== System: Linux OX7060-Jeedom.xxxxxxxxxx 4.19.0-23-amd64 #1 SMP Debian 4.19.269-1 (2022-12-20) x86_64 GNU/Linux
== Jeedom version: 4.3.17
== jMQTT version: 2023-04-15
[  9% ] : Checking parameters : [  OK  ]
[ 10% ] : Synchronize the package index...
[ 19% ] : Synchronize the package index : [  OK  ]
[ 20% ] : Install Composer...
[ 29% ] : Install Composer : [  OK  ]
[ 30% ] : Install JsonPath-PHP library...
[ 39% ] : Install JsonPath-PHP library : [  OK  ]
[ 40% ] : Remove Composer...
[ 49% ] : Remove Composer : [  OK  ]
[ 50% ] : Install python3 venv and pip debian packages...
[ 59% ] : Install python3 venv and pip debian packages : [  OK  ]
[ 60% ] : Create a python3 Virtual Environment...
[ 69% ] : Create a python3 Virtual Environment : [  OK  ]
[ 70% ] : Install required python3 libraries in venv...
[ 99% ] : Install required python3 libraries in venv : [  OK  ]
[100% ] : Finished !

======================================================================
== OK == Installation Successful 
======================================================================

[Bad]

Hello @JC38,

Je ne pense pas que ça soit en rapport avec le problème initial, mais avec son accord, on peut continuer dans ce sujet (qui au final concerne des problèmes potentiels avec la dernière stable).

Quand tu as les logs en debug jMQTT et jMQTTd n’hésite pas à me les envoyer en fichier par message privé.

EDIT: As-tu rebooté depuis l’installation ? Je pense que la class jMQTT ne s’est pas mise à jour quelle que part dans le code Jeedom qui tourne actuellement.

[rennais35000]

Coucou Bad au passage,
Surtout que les premières lignes sont du 10 et 11 et que ça le faisait déjà alors que la maj est du 15

[Bad]

J’ai pris ces logs pour des arrêts « volontaires » du plugin vu que bien plus espacées, mais à voir en effet.

[JC38]

Oui, c’est bien le cas tous les restart antérieurs étaient volontaires. Sauf 1 vers 6h40 le 11 avril.
Je redémarre. Juste avant, je viens de regarder les logs en debug vite fait :

[2023-04-16 10:56:51,865][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 5.329847ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:56:51,865][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 4.751921ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:57:02,285][DEBUG] Main            MainThread             run() : Heartbeat received from Jeedom
[2023-04-16 10:57:36,875][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 10:57:36,875][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 10:57:36,893][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 21.189451ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:57:36,894][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 18.945694ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:58:02,640][DEBUG] Main            MainThread             run() : Heartbeat received from Jeedom
[2023-04-16 10:58:21,904][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 10:58:21,905][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 10:58:21,921][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 19.607782ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:58:21,922][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 17.379761ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:59:01,894][DEBUG] Main            MainThread             run() : Heartbeat received from Jeedom
[2023-04-16 10:59:06,935][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 10:59:06,935][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 10:59:06,950][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 15.990496ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:59:06,951][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 15.528917ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:59:51,967][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 10:59:51,967][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 10:59:51,979][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 12.305975ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 10:59:51,980][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 16.580105ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:00:02,446][DEBUG] Main            MainThread             run() : Heartbeat received from Jeedom
[2023-04-16 11:00:37,065][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 11:00:37,065][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 11:00:37,075][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 13.201475ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:00:37,075][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 9.570122ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:01:22,083][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 11:01:22,083][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 11:01:22,103][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 19.699097ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:01:22,105][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 22.591114ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:02:07,110][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2023-04-16 11:02:07,113][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2023-04-16 11:02:07,134][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 19.872665ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:02:07,135][DEBUG] JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 25.762081ms (qToJ size 0): [{'cmd': 'hb'}]
[2023-04-16 11:02:17,460][ERROR] JMsg.Rcv        MainThread      is_working() : Nothing has been received for 135s (max 135s), Jeedom does not want me any longer.
[2023-04-16 11:02:17,561][INFO] Main            MainThread        shutdown() : Stop jMQTT python daemon
[2023-04-16 11:02:17,561][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stop requested
[2023-04-16 11:02:18,043][INFO] JMsg.Rcv        SockIn            _loopRcv() : Stopped
[2023-04-16 11:02:18,044][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stopped
[2023-04-16 11:02:18,044][DEBUG] JMsg.Snd        Brk2771Th       send_async() : Enqued the message in 0.003576ms (qToJ size 1): {'cmd': 'brokerDown', 'id': '2771'}
[2023-04-16 11:02:18,045][INFO] Client2771      Brk2771Th    on_disconnect() : Disconnected from broker.
[2023-04-16 11:02:18,045][DEBUG] Client2771      MainThread            stop() : jMqttClient ended
[2023-04-16 11:02:18,045][DEBUG] Main            MainThread        shutdown() : Sent Daemon Down signal to Jeedom
[2023-04-16 11:02:18,046][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stop requested
[2023-04-16 11:02:18,062][INFO] JMsg.Snd        SockOut           _loopSnd() : Stopped
[2023-04-16 11:02:18,062][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stopped
[2023-04-16 11:02:18,144][DEBUG] JMsg.Snd        MainThread            send() : Sent TO Jeedom 1 messages handled in 81.569195ms (qToJ size 0): [{'cmd': 'daemonDown'}]
[2023-04-16 11:02:18,144][DEBUG] root            MainThread        <module>() : Exit 0

[Bad]

Ouep c’est ce que je pensais !

@JC38

Nothing has been received for 135s (max 135s), Jeedom does not want me any longer.

Et j’imagine que dans le log jMQTT (en debug) tu ne vois jamais ce type de ligne :
Envoi d'un Heartbeat au Démon (rien n'a été envoyé depuis >45s).

Des fois l’ancien code de la classe jMQTT est conservé par le Core.
Pour corriger, soit il faut que tu reboot, soit que tu arrêtes tous les crons, démon et scénario en fonctionnement. (bref, reboot)

EDIT :
Je ne pense finalement pas qu’un reboot marchera, car en fait il doit y avoir des messages Envoi d'un Heartbeat au Démon (dans le log jMQTT), vu qu’on voit des messages Heartbeat received from Jeedom (dans le log jMQTTd), mais il doit y en avoir une fois par minute et on voit bien qu’il en manque 2 (11:01 et 11:02) c’est pas normal. Quelle est la charge CPU de ton système ?

J’y jette un 2eme coup d’oeuil, mais si tu veux bien reboot par acquis de conscience.

[JC38]

J’ai fait le reboot, il semble bien que le pb soit toujours présent. Mais je dois m’absenter. J’enverrai des news asap.

[Bad]

Ok, je vais essayer de reproduire dans l’intervalle.

EDIT : @JC38, j’ai réussi à reproduire, mais uniquement quand :

• aucune page du plugin n’est ouverte dans un navigateur,
• il n’y a pas d’action (publish) de Jeedom en MQTT pendant assez longtemps (> 135s) ET
• le cron est désactivé sur le plugin jMQTT :
  
  

Donc 2 option :

• Le cron est désactivé

• Le cron « plugin 1 minute » de Jeedom peut prendre plus de 2 minutes sur ton système, peut-être par ce que un autre plugin le monopolise, cf « Moteur de taches » :
  

  

Tiens-moi au jus.

[JC38]

Voici pour la config du plugin. Pourquoi le pb serait apparu qu’à partir de la dernière version ? Je regarde pour le moteur des tâches.
Vu ma config, je pense également que je pourrais désinstaller Mosquitto de mon jeedom. Il tourne maintenant sur un Windows server.
Au début, mqqt2 est venu avec Z-wave JS ou JeeZigbee, je ne me souviens plus lequel j’ai mis en premier. Ensuite, je suis passé avec un Zigbee2mqtt sur mon Windows, mais sans trop toucher à mqtt2 sauf pour lui dire que j’étais passé sur un broker distant. Je pense que Mosquitto sur mon Jeedom ne sert plus à rien.
Par la suite, j’ai testé JMQTT pour pouvoir connecter ma serrure Nuki qui faisait planter mqtt2. J’ai ensuite ajouté un second Zigbee2Mqtt sur mon Windows avec un topic différent, ce qui me permet d’avoir 2 clés Zigbee avec 2 Zigbee2Mqtt depuis une même machine.
En attendant, voici le screenshot de la config de JMQTT

[Bad]

Hello,

@JC38

Pourquoi le pb serait apparu qu’à partir de la dernière version ? Je regarde pour le moteur des tâches.

C’est ce point du changelog qui (ré)introduit la mort du démon quand il n’a rien vu passer de Jeedom :

Correction des problèmes de multi-lancement du démon avec des signaux de vie entre le démon et Jeedom

@JC38

Vu ma config, je pense également que je pourrais désinstaller Mosquitto de mon jeedom. Il tourne maintenant sur un Windows server.

Si tu es sur que mqtt2 utilise le bon Mosquitto, oui en effet.
Dans un 1er temps, tu peux l’Arrêter à l’aide de jMQTT pour faire le test.

C’est bien activé, donc pas ça.
Donc c’est ton cron plugin 1 minute qui doit prendre trop de temps (de temps en temps).
Tu peux le check stp ?

[JC38]

Pour info, le plugin wifilight2 a été mis à jour en même temps que jmqtt

[Bad]

Il n’a pourtant pas l’air méga chargé :

Je te MP pour diag en direct

[Toms]

Bonsoir @Bad,

Je viens d’effectuer quelques tests pour sauvegarder mes équipements et ma configuration JMQTT,
Tout est OK et semble très bien fonctionner,

[2023-04-16 22:56:55]INFO : ###########################################################
[2023-04-16 22:56:55]INFO : Starting jMQTT backup...
[2023-04-16 22:56:55]INFO : Writing PID file...                                  [ OK ]
[2023-04-16 22:56:55]INFO : Cleaning up...                                       [ OK ]
[2023-04-16 22:56:55]INFO : Preparing to backup...                               [ OK ]
[2023-04-16 22:56:55]INFO : Generating index file...                             [ OK ]
[2023-04-16 22:56:55]INFO : Generating Data file...                              [ OK ]
[2023-04-16 22:56:55]INFO : Generating History file...                           [ OK ]
[2023-04-16 22:57:08]INFO : Backing up jMQTT folder...                           [ OK ]
[2023-04-16 22:57:08]INFO : Backing up jMQTT log files...                        [ OK ]
[2023-04-16 22:57:08]INFO : Mosquitto folder in /etc is missing             [ SKIPPED ]
[2023-04-16 22:57:08]INFO : Generating Metadata file...                          [ OK ]
[2023-04-16 22:57:08]INFO : Creating archive jMQTT_20230416_225708.tgz...        [ OK ]
[2023-04-16 22:57:11]INFO : Cleaning up...                                       [ OK ]
[2023-04-16 22:57:11]INFO : Removing PID file...                                 [ OK ]
[2023-04-16 22:57:11]INFO : End of jMQTT backup.
[2023-04-16 22:57:11]INFO : ###########################################################

Concernant la partie Sauvegardes disponibles, j’ai une proposition d’amélioration. Serait-il possible de pouvoir créer l’option d’envoyer automatiquement la sauvegarde vers un autre disque dur ? Avoir également le choix de la rétention et de la fréquence de la sauvegarde,
Par exemple, avec le core de Jeedom on peut envoyer notre backup vers un disque dur sur notre réseau local,

Au plaisir,
Bonne soirée,

[Bad]

Hello,

@Toms

Serait-il possible de pouvoir créer l’option d’envoyer automatiquement la sauvegarde vers un autre disque dur ? Avoir également le choix de la rétention et de la fréquence de la sauvegarde,

Alors en fait toute la config de jMQTT est déjà sauvegardée nativement par le Core et présente dans les backup du Core (sauf Mosquitto).

Pour être honnête, j’utilisais jusque là un vieux bout de code pour restaurer tous mes équipements dans un état stable avant de pouvoir faire les captures d’écran de la doc ou les tests de montée de version avant de libérer une version.
Je me disais que ça pourrait servir à d’autres qui souhaitent restaurer jMQTT en l’état et en gardant les id et les liaisons, sans toucher aux autres plugins, mais ça n’a pas vocation à évoluer pour faire des backups externalisées, vu que tout est déjà dans la backup de Jeedom.

Sachant aussi qu’il reste des bugs dans ce code : la sauvegarde d’un historique très important (>100Mo) peut échouer par manque de RAM (corrigé dans ma version locale) et la restauration ne restaure pas bien les la version du plugin dans la page de mise à jour, les caches des équipements et 3-4 subtilités…

Bad

[Yogui]

@Bad
deja merci pour le temps investi sur le dev du plugin.

j’ai également le problème remonté par @JC38
j’ai également le plugin MQTT2 (officiel jeedom) qui pointe sur le même serveur mosquito (distant) que JMQTT (je viens de le désactive en lisant les postes précédents car je ne m’en sert pas encore)
J’ai également passer le log en debug
La derniere foi que j’ai eu l’erreur j’etais juste au pc et j’ai pu voir que le demon de JMQTT était arrêté

[Bad]

Hello,

Suite à mon analyse d’hier soir sur le Jeedom de @JC38, j’ai identifié que le cron plugin est effectivement saturé et qu’il y mets souvent plus d’une minute à s’exécuter.

J’ai remonté les timeouts à 300s au lieu de 135s sur sa machine, et nous allons chercher à identifier le plugin qui « prend son temps » sur ce cron.

Pour info, la machine n’est pas surchargée et le démon se coupe suite à un mécanisme (ré)introduit dans la dernière version pour éviter les démons fantômes. Il n’y aurait pas de problème (au contraire) si plus d’équipement publiaient et récupéraient des infos en MQTT (évitant ainsi le timeout).

Bad

[Yogui]

merci @Bad pour ta réponse rapide
j’ai aussi vu un cron plugin chez moi a 128s…
voici ma liste de plugin histoire de pouvoir faire peu etre un lien entre mes plugin et ceux de @JC38

comme dit plus haut MQTT2 et ZwaveJS sont desactivé depuis ce matin

par contre les 128s on été detectés apres cette désactivation

[Bad]

@Yogui

par contre les 128s on été detectés apres cette désactivation

Oui, je ne pense pas que ce soit lié à un plugin avec un démon, ni un cron dédié et probablement pas un plugin officiel, vu qu’il est bien connu de l’équipe qu’il faut éviter de faire tourner des tâches longues dans ce cron.

Chez JC38, j’ai rajouté des debug dans le core ( class plugin, fonction cron() ) pour voir qui bouffe tout le temps, mais il n’y avait pas d’affichage et il était trop tard hier soir pour reboot.

Mais tu peux essayer de chercher le fautif « à l’ancienne » en désactivant un plugin à la fois et en regardant le résultat sur le temps d’exécution du cron.

Bad

[Toms]

Lo,

Je comprends ton point de vu,
Effectivement lors d’une sauvegarde du core, après restauration tous les équipements ajoutés au plugin reviennent dans son intégralités, Merci pour ton retour et l’intérêt porté à mes questions,

Bonne fin de journée,
Au plaisir,

[Yogui]

petite info complémentaire le problème arrive a chaque foi que le cron est exécuter je pense en meme temps que le cron 5 min ,pas sur de cette information mais je viens de tester en effaçant l’erreur a chaque foi quelle apparaît et j’ai eu l’erreur a 18:25:04, 18:30:04 , 18:35:04 , 18:40:04