Demon-nok-avec-la-version-2022-07-24 01:01:15

regi54 · Juillet 24, 2022, 7:12

Bonjour, Depuis la mise à jours d’hier je ne peux plus lancer le démon JMQTT je suis sur une version DIY sous debian sur un PC dédié.
Voici le log jMQTTd

[2022-07-24 09:04:32,038]INFO Main            MainThread   set_log_level() : New log level set to: DEBUG
[2022-07-24 09:04:32,039]DEBUG Main            MainThread         prepare() : Writing PID 1270954 to /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 09:04:32,040]INFO Main            MainThread         prepare() : Log level   : debug
[2022-07-24 09:04:32,040]INFO Main            MainThread         prepare() : Socket port : 0
[2022-07-24 09:04:32,040]INFO Main            MainThread         prepare() : Callback url: http://localhost:80/plugins/jMQTT/core/php/callback.php
[2022-07-24 09:04:32,041]INFO Main            MainThread         prepare() : PID file    : /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 09:04:32,041]DEBUG Main            MainThread         prepare() : Apikey      : lZYCAL404KEOLGcXWekUsIvRiKdTGdad
[2022-07-24 09:04:32,041]DEBUG JMsg.Rcv        MainThread  receiver_start() : Start requested
[2022-07-24 09:04:32,042]DEBUG JMsg.Rcv        SockIn            _loopRcv() : Start
[2022-07-24 09:04:32,043]INFO JMsg.Rcv        MainThread  receiver_start() : Started, listening on [127.0.0.1:46705]
[2022-07-24 09:04:32,077]DEBUG JMsg.Snd        MainThread       send_test() : Test successful
[2022-07-24 09:04:32,077]DEBUG JMsg.Snd        MainThread    sender_start() : Start requested
[2022-07-24 09:04:32,078]DEBUG JMsg.Snd        SockOut           _loopSnd() : Start
[2022-07-24 09:04:32,079]INFO JMsg.Snd        MainThread    sender_start() : Started
[2022-07-24 09:04:32,079]DEBUG JMsg.Snd        MainThread      send_async() : Enqued message: {'cmd': 'daemonUp'}
[2022-07-24 09:04:32,080]DEBUG Main            MainThread       open_comm() : Open Comm   : Sent Daemon Up signal to Jeedom
[2022-07-24 09:04:32,180]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 09:04:32,337]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'daemonUp'}]
[2022-07-24 09:04:58,130]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 09:04:58,169]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 09:05:17,399]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 09:05:17,438]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 09:05:43,245]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 09:05:43,287]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 09:06:02,139]ERROR JMsg.Rcv        MainThread      is_working() : Nothing has been received since 90s (max 90), Jeedom is probably dead.
[2022-07-24 09:06:02,240]INFO Main            MainThread        shutdown() : Stop jMQTT python daemon
[2022-07-24 09:06:02,240]DEBUG JMsg.Rcv        MainThread   receiver_stop() : Stop requested
[2022-07-24 09:06:02,493]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 09:06:02,522]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 09:06:02,719]INFO JMsg.Rcv        SockIn            _loopRcv() : Stopped
[2022-07-24 09:06:02,720]DEBUG JMsg.Rcv        MainThread   receiver_stop() : Stopped
[2022-07-24 09:06:02,720]DEBUG Main            MainThread        shutdown() : Sent Daemon Down signal to Jeedom
[2022-07-24 09:06:02,720]DEBUG JMsg.Snd        MainThread     sender_stop() : Stop requested
[2022-07-24 09:06:02,723]INFO JMsg.Snd        SockOut           _loopSnd() : Stopped
[2022-07-24 09:06:02,724]DEBUG JMsg.Snd        MainThread     sender_stop() : Stopped
[2022-07-24 09:06:02,742]DEBUG JMsg.Snd        MainThread            send() : Sent TO Jeedom: [{'cmd': 'daemonDown'}]
[2022-07-24 09:06:02,743]DEBUG root            MainThread        () : Exit 0

Celui de jMQTT:

[2022-07-24 09:08:56][ERROR] : Attention je pense qu'il y a un soucis avec le démon que j'ai relancé plus de 3 fois consécutivement
[2022-07-24 09:08:56][INFO] : Démarrage du démon jMQTT
[2022-07-24 09:08:56][DEBUG] : Nettoyage du Démon
[2022-07-24 09:09:01][INFO] : Lancement du démon jMQTT
[2022-07-24 09:09:02][WARNING] : Démon [1280952:45399] : N'a pas pû être authentifié
[2022-07-24 09:09:11][DEBUG] : Nettoyage du Démon
[2022-07-24 09:09:11][ERROR] : Impossible de lancer le démon jMQTT, vérifiez le log
[2022-07-24 09:09:28][DEBUG] : Démon [3366:37887] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'

Info santé:

J’ai réinstallé le plugin et les dépendances mais aucune différence.
l’url : http://x.x.x.x.x:80/plugins/jMQTT/core/php/callback.php
me donne bien :Unauthorized access

Bad · Juillet 24, 2022, 7:32

Hello @regi54,

Désolé pour ce souci.
Au vu des logs, tu dois encore avoir un autre daemon jmqtt qui tourne :

Et les daemon rejetés sont 1280952:45399 ou 3366:37887 (PID:port)

Peux-tu faire un ps auxfw | grep -i jmqtt et tuer tous les daemons restants ?

Je pense que, lors de la maj, l’ancien daemon n’a pas été tué… Dans la version de cette nuit, les Heartbeats sont justement là pour qu’un daemon qui n’arrive pas à joindre Jeedom meurt, ce n’était pas le cas avant.

Bad

regi54 · Juillet 24, 2022, 9:52

J’ai kill tout les process JMQTT et j’ai redémarré le PC, puis la commande:

ps auxfw | grep -i jmqtt

voici le retour de la commande:

pi       1577227  0.0  0.0   6196   724 pts/0    S+   11:47   0:00              \_ grep -i jmqtt
www-data 1575154  1.1  0.5 177680 22328 ?        Sl   11:46   0:00 /var/www/html/plugins/jMQTT/resources/jmqttd/venv/bin/python3 /var/www/html/plugins/jMQTT/resources/jmqttd/jmqttd.py --loglevel error --callback http://localhost:80/plugins/jMQTT/core/php/callback.php --apikey lZYCAL404KEOLGcXWekUsIvRiKdTGdad --pid /tmp/jeedom/jMQTT/jmqttd.py.pid
www-data 1576640  3.2  0.5 177680 22340 ?        Sl   11:46   0:00 /var/www/html/plugins/jMQTT/resources/jmqttd/venv/bin/python3 /var/www/html/plugins/jMQTT/resources/jmqttd/jmqttd.py --loglevel error --callback http://localhost:80/plugins/jMQTT/core/php/callback.php --apikey lZYCAL404KEOLGcXWekUsIvRiKdTGdad --pid /tmp/jeedom/jMQTT/jmqttd.py.pid

le demon ne demarrer toujours pas:
jMQTTd:


[2022-07-24 11:49:00,589][INFO] Main            MainThread   set_log_level() : New log level set to: DEBUG
[2022-07-24 11:49:00,590][DEBUG] Main            MainThread         prepare() : Writing PID 1580584 to /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 11:49:00,591][INFO] Main            MainThread         prepare() : Log level   : debug
[2022-07-24 11:49:00,591][INFO] Main            MainThread         prepare() : Socket port : 0
[2022-07-24 11:49:00,591][INFO] Main            MainThread         prepare() : Callback url: http://localhost:80/plugins/jMQTT/core/php/callback.php
[2022-07-24 11:49:00,591][INFO] Main            MainThread         prepare() : PID file    : /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 11:49:00,592][DEBUG] Main            MainThread         prepare() : Apikey      : lZYCAL404KEOLGcXWekUsIvRiKdTGdad
[2022-07-24 11:49:00,592][DEBUG] JMsg.Rcv        MainThread  receiver_start() : Start requested
[2022-07-24 11:49:00,593][DEBUG] JMsg.Rcv        SockIn            _loopRcv() : Start
[2022-07-24 11:49:00,594][INFO] JMsg.Rcv        MainThread  receiver_start() : Started, listening on [127.0.0.1:43507]
[2022-07-24 11:49:00,616][DEBUG] JMsg.Snd        MainThread       send_test() : Test successful
[2022-07-24 11:49:00,617][DEBUG] JMsg.Snd        MainThread    sender_start() : Start requested
[2022-07-24 11:49:00,618][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Start
[2022-07-24 11:49:00,618][INFO] JMsg.Snd        MainThread    sender_start() : Started
[2022-07-24 11:49:00,619][DEBUG] JMsg.Snd        MainThread      send_async() : Enqued message: {'cmd': 'daemonUp'}
[2022-07-24 11:49:00,619][DEBUG] Main            MainThread       open_comm() : Open Comm   : Sent Daemon Up signal to Jeedom
[2022-07-24 11:49:00,719][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 11:49:00,866][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'daemonUp'}]
[2022-07-24 11:49:45,935][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 11:49:45,963][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 11:50:09,403][INFO] Main            MainThread   set_log_level() : New log level set to: DEBUG
[2022-07-24 11:50:09,403][DEBUG] Main            MainThread         prepare() : Writing PID 1583317 to /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 11:50:09,404][INFO] Main            MainThread         prepare() : Log level   : debug
[2022-07-24 11:50:09,405][INFO] Main            MainThread         prepare() : Socket port : 0
[2022-07-24 11:50:09,405][INFO] Main            MainThread         prepare() : Callback url: http://localhost:80/plugins/jMQTT/core/php/callback.php
[2022-07-24 11:50:09,405][INFO] Main            MainThread         prepare() : PID file    : /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 11:50:09,405][DEBUG] Main            MainThread         prepare() : Apikey      : lZYCAL404KEOLGcXWekUsIvRiKdTGdad
[2022-07-24 11:50:09,405][DEBUG] JMsg.Rcv        MainThread  receiver_start() : Start requested
[2022-07-24 11:50:09,407][DEBUG] JMsg.Rcv        SockIn            _loopRcv() : Start
[2022-07-24 11:50:09,408][INFO] JMsg.Rcv        MainThread  receiver_start() : Started, listening on [127.0.0.1:41559]
[2022-07-24 11:50:09,426][DEBUG] JMsg.Snd        MainThread       send_test() : Test successful
[2022-07-24 11:50:09,427][DEBUG] JMsg.Snd        MainThread    sender_start() : Start requested
[2022-07-24 11:50:09,429][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Start
[2022-07-24 11:50:09,430][INFO] JMsg.Snd        MainThread    sender_start() : Started
[2022-07-24 11:50:09,430][DEBUG] JMsg.Snd        MainThread      send_async() : Enqued message: {'cmd': 'daemonUp'}
[2022-07-24 11:50:09,430][DEBUG] Main            MainThread       open_comm() : Open Comm   : Sent Daemon Up signal to Jeedom
[2022-07-24 11:50:09,530][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 11:50:09,696][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'daemonUp'}]
[2022-07-24 11:50:30,664][ERROR] JMsg.Rcv        MainThread      is_working() : Nothing has been received since 90s (max 90), Jeedom is probably dead.
[2022-07-24 11:50:30,765][INFO] Main            MainThread        shutdown() : Stop jMQTT python daemon
[2022-07-24 11:50:30,765][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stop requested
[2022-07-24 11:50:30,768][INFO] JMsg.Rcv        SockIn            _loopRcv() : Stopped
[2022-07-24 11:50:30,768][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stopped
[2022-07-24 11:50:30,769][DEBUG] Main            MainThread        shutdown() : Sent Daemon Down signal to Jeedom
[2022-07-24 11:50:30,769][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stop requested
[2022-07-24 11:50:30,809][INFO] JMsg.Snd        SockOut           _loopSnd() : Stopped
[2022-07-24 11:50:30,809][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stopped
[2022-07-24 11:50:30,833][DEBUG] JMsg.Snd        MainThread            send() : Sent TO Jeedom: [{'cmd': 'daemonDown'}]
[2022-07-24 11:50:30,834][DEBUG] root            MainThread        <module>() : Exit 0

JMQTT:

[2022-07-24 11:48:55][INFO] : Démarrage du démon jMQTT
[2022-07-24 11:48:55][DEBUG] : Nettoyage du Démon
[2022-07-24 11:49:00][INFO] : Lancement du démon jMQTT
[2022-07-24 11:49:00][WARNING] : Démon [1580584:43507] : N'a pas pû être authentifié
[2022-07-24 11:49:10][DEBUG] : Nettoyage du Démon
[2022-07-24 11:49:10][ERROR] : Impossible de lancer le démon jMQTT, vérifiez le log
[2022-07-24 11:49:45][DEBUG] : Démon [1580584:43507] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-24 11:50:03][ERROR] : Attention je pense qu'il y a un soucis avec le démon que j'ai relancé plus de 3 fois consécutivement
[2022-07-24 11:50:03][INFO] : Démarrage du démon jMQTT
[2022-07-24 11:50:03][DEBUG] : Nettoyage du Démon
[2022-07-24 11:50:08][INFO] : Lancement du démon jMQTT
[2022-07-24 11:50:09][WARNING] : Démon [1583317:41559] : N'a pas pû être authentifié
[2022-07-24 11:50:18][DEBUG] : Nettoyage du Démon
[2022-07-24 11:50:19][ERROR] : Impossible de lancer le démon jMQTT, vérifiez le log
[2022-07-24 11:50:30][DEBUG] : Démon [1580584:43507] : Impossible d'autoriser la cmd 'daemonDown' avant la commande 'daemonUp': '{"cmd":"daemonDown"}'
[2022-07-24 11:50:54][DEBUG] : Démon [1583317:41559] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'

après avoir redemaré:

root       32005  0.0  0.0   6196   660 pts/0    S+   12:15   0:00                          \_ grep -i jmqtt
www-data   31612  4.0  0.5 177332 22716 ?        Sl   12:15   0:00 /var/www/html/plugins/jMQTT/resources/jmqttd/venv/bin/python3 /var/www/html/plugins/jMQTT/resources/jmqttd/jmqttd.py --loglevel debug --callback http://localhost:80/plugins/jMQTT/core/php/callback.php --apikey lZYCAL404KEOLGcXWekUsIvRiKdTGdad --pid /tmp/jeedom/jMQTT/jmqttd.py.pid

Bad · Juillet 24, 2022, 10:45

Merci pour ces éléments

Redémarrer le daemon ou tout Jeedom ?

Est-ce que jMQTT fonctionne (maintenant avant redémarrage, et après) ?

Bad

regi54 · Juillet 24, 2022, 6:31

Redémarrer tous le PC donc Jeedom complet, et jMQTT ne fonctionne pas ce sont les log après redémarrage que j’ai posté.

Bad · Juillet 24, 2022, 6:45

Hello @regi54,

Je pense que tu as le même problème que kristobal, regarde pour appliquer ce patch :

regi54 · Juillet 24, 2022, 7:52

Toujours pareil j’ai fait la modification dans le fichier :

jMQTT/core/class/jMQTT.class.php

redémarré le PC et le démon ne démarre pas:

[2022-07-24 21:47:40,564][INFO] Main            MainThread   set_log_level() : New log level set to: DEBUG
[2022-07-24 21:47:40,564][DEBUG] Main            MainThread         prepare() : Writing PID 1077587 to /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 21:47:40,565][INFO] Main            MainThread         prepare() : Log level   : debug
[2022-07-24 21:47:40,565][INFO] Main            MainThread         prepare() : Socket port : 0
[2022-07-24 21:47:40,566][INFO] Main            MainThread         prepare() : Callback url: http://localhost:80/plugins/jMQTT/core/php/callback.php
[2022-07-24 21:47:40,566][INFO] Main            MainThread         prepare() : PID file    : /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-24 21:47:40,566][DEBUG] Main            MainThread         prepare() : Apikey      : lZYCAL404KEOLGcXWekUsIvRiKdTGdad
[2022-07-24 21:47:40,566][DEBUG] JMsg.Rcv        MainThread  receiver_start() : Start requested
[2022-07-24 21:47:40,568][DEBUG] JMsg.Rcv        SockIn            _loopRcv() : Start
[2022-07-24 21:47:40,569][INFO] JMsg.Rcv        MainThread  receiver_start() : Started, listening on [127.0.0.1:40029]
[2022-07-24 21:47:40,599][DEBUG] JMsg.Snd        MainThread       send_test() : Test successful
[2022-07-24 21:47:40,600][DEBUG] JMsg.Snd        MainThread    sender_start() : Start requested
[2022-07-24 21:47:40,601][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Start
[2022-07-24 21:47:40,601][INFO] JMsg.Snd        MainThread    sender_start() : Started
[2022-07-24 21:47:40,601][DEBUG] JMsg.Snd        MainThread      send_async() : Enqued message: {'cmd': 'daemonUp'}
[2022-07-24 21:47:40,601][DEBUG] Main            MainThread       open_comm() : Open Comm   : Sent Daemon Up signal to Jeedom
[2022-07-24 21:47:40,702][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 21:47:40,880][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'daemonUp'}]
[2022-07-24 21:48:25,946][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 21:48:25,982][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 21:49:10,648][ERROR] JMsg.Rcv        MainThread      is_working() : Nothing has been received since 90s (max 90), Jeedom is probably dead.
[2022-07-24 21:49:10,749][INFO] Main            MainThread        shutdown() : Stop jMQTT python daemon
[2022-07-24 21:49:10,750][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stop requested
[2022-07-24 21:49:11,037][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-24 21:49:11,066][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-24 21:49:11,238][INFO] JMsg.Rcv        SockIn            _loopRcv() : Stopped
[2022-07-24 21:49:11,239][DEBUG] JMsg.Rcv        MainThread   receiver_stop() : Stopped
[2022-07-24 21:49:11,240][DEBUG] Main            MainThread        shutdown() : Sent Daemon Down signal to Jeedom
[2022-07-24 21:49:11,240][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stop requested
[2022-07-24 21:49:11,268][INFO] JMsg.Snd        SockOut           _loopSnd() : Stopped
[2022-07-24 21:49:11,269][DEBUG] JMsg.Snd        MainThread     sender_stop() : Stopped
[2022-07-24 21:49:11,317][DEBUG] JMsg.Snd        MainThread            send() : Sent TO Jeedom: [{'cmd': 'daemonDown'}]
[2022-07-24 21:49:11,318][DEBUG] root            MainThread        <module>() : Exit 0

[2022-07-24 21:47:40]INFO : Lancement du démon jMQTT
[2022-07-24 21:47:40]WARNING : Démon [1077587:40029] : N'a pas pû être authentifié
[2022-07-24 21:47:50]DEBUG : Nettoyage du Démon
[2022-07-24 21:47:50]ERROR : Impossible de lancer le démon jMQTT, vérifiez le log
[2022-07-24 21:48:25]DEBUG : Démon [1077587:40029] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-24 21:49:11]DEBUG : Démon [1077587:40029] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-24 21:49:11]DEBUG : Démon [1077587:40029] : Impossible d'autoriser la cmd 'daemonDown' avant la commande 'daemonUp': '{"cmd":"daemonDown"}'

ps auxfw | grep -i jmqtt

root     1085846  0.0  0.0   6196   664 pts/0    S+   21:51   0:00                          \_ grep -i jmqtt

Bad · Juillet 24, 2022, 8:18

Hello @regi54,

On vient de faire un partage Discord avec kristobal.
Je pense que j’ai identifié la source, mais il faut que je fasse plus de tests.

En attendant, je te propose de désactiver simplement le mécanisme de « mort » du Daemon :
Dans jMQTT/resources/jmqttd/jmqttd.py vers la ligne 206, remplace ça :

		# Wait for instructions
		while not self.should_stop.is_set():
			# empty() method is faster that Exception handling
			if not self.jcom.is_working(): # Check if there has been bidirectional communication with Jeedon
				self.should_stop.set()
			if self.jcom.qFromJ.empty():

par ça :

		# Wait for instructions
		while not self.should_stop.is_set():
			# empty() method is faster that Exception handling
#			if not self.jcom.is_working(): # Check if there has been bidirectional communication with Jeedon
#				self.should_stop.set()
			if self.jcom.qFromJ.empty():

Dit moi si ça règle le problème pour toi.

EDIT: Ce sera dans la version stable de demain, tu auras juste à update.

Bad

regi54 · Juillet 25, 2022, 5:41

Le demon ne demarre toujours pas:


[2022-07-25 07:31:07,465][INFO] Main            MainThread   set_log_level() : New log level set to: DEBUG
[2022-07-25 07:31:07,466][DEBUG] Main            MainThread         prepare() : Writing PID 2157107 to /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-25 07:31:07,467][INFO] Main            MainThread         prepare() : Log level   : debug
[2022-07-25 07:31:07,467][INFO] Main            MainThread         prepare() : Socket port : 0
[2022-07-25 07:31:07,467][INFO] Main            MainThread         prepare() : Callback url: http://localhost:80/plugins/jMQTT/core/php/callback.php
[2022-07-25 07:31:07,468][INFO] Main            MainThread         prepare() : PID file    : /tmp/jeedom/jMQTT/jmqttd.py.pid
[2022-07-25 07:31:07,468][DEBUG] Main            MainThread         prepare() : Apikey      : lZYCAL404KEOLGcXWekUsIvRiKdTGdad
[2022-07-25 07:31:07,468][DEBUG] JMsg.Rcv        MainThread  receiver_start() : Start requested
[2022-07-25 07:31:07,469][DEBUG] JMsg.Rcv        SockIn            _loopRcv() : Start
[2022-07-25 07:31:07,470][INFO] JMsg.Rcv        MainThread  receiver_start() : Started, listening on [127.0.0.1:35961]
[2022-07-25 07:31:07,496][DEBUG] JMsg.Snd        MainThread       send_test() : Test successful
[2022-07-25 07:31:07,496][DEBUG] JMsg.Snd        MainThread    sender_start() : Start requested
[2022-07-25 07:31:07,497][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Start
[2022-07-25 07:31:07,498][INFO] JMsg.Snd        MainThread    sender_start() : Started
[2022-07-25 07:31:07,499][DEBUG] JMsg.Snd        MainThread      send_async() : Enqued message: {'cmd': 'daemonUp'}
[2022-07-25 07:31:07,499][DEBUG] Main            MainThread       open_comm() : Open Comm   : Sent Daemon Up signal to Jeedom
[2022-07-25 07:31:07,598][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:31:07,736][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'daemonUp'}]
[2022-07-25 07:31:52,811][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:31:52,854][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:32:37,904][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:32:37,933][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:33:23,001][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:33:23,035][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:34:08,109][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:34:08,138][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:34:53,199][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:34:53,235][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:35:38,302][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:35:38,344][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:36:23,383][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:36:23,424][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:37:08,488][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:37:08,521][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:37:53,590][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:37:53,638][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:38:38,712][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:38:38,755][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:39:23,816][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:39:23,855][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]
[2022-07-25 07:40:08,890][DEBUG] JMsg.Snd        SockOut           _loopSnd() : Sending 1 msgs
[2022-07-25 07:40:08,945][DEBUG] JMsg.Snd        SockOut               send() : Sent TO Jeedom: [{'cmd': 'hb'}]

[2022-07-25 07:31:01]ERROR : Attention je pense qu'il y a un soucis avec le démon que j'ai relancé plus de 3 fois consécutivement
[2022-07-25 07:31:01]INFO : Démarrage du démon jMQTT
[2022-07-25 07:31:01]DEBUG : Nettoyage du Démon
[2022-07-25 07:31:07]INFO : Lancement du démon jMQTT
[2022-07-25 07:31:07]WARNING : Démon [2157107:35961] : N'a pas pû être authentifié
[2022-07-25 07:31:17]DEBUG : Nettoyage du Démon
[2022-07-25 07:31:17]ERROR : Impossible de lancer le démon jMQTT, vérifiez le log
[2022-07-25 07:31:52]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:32:37]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:33:23]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:34:08]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:34:53]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:35:38]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:36:23]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:37:08]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:37:53]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:38:38]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:39:23]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:40:08]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'
[2022-07-25 07:40:54]DEBUG : Démon [2157107:35961] : Impossible d'autoriser la cmd 'hb' avant la commande 'daemonUp': '{"cmd":"hb"}'

Bad · Juillet 25, 2022, 6:02

Je vois effectivement que le process du daemon est démarré mais Jeedom ne l’accepte pas.

Peux-tu me faire un netstat -lntp stp ?

Tu es bien sous debian 10 ?

Si tu as 15mins, avant 9h, on peut faire un partage d’écran sur Discord.

regi54 · Juillet 25, 2022, 6:26

Voici la réponse:

Connexions Internet actives (seulement serveurs)
Proto Recv-Q Send-Q Adresse locale          Adresse distante        Etat        PID/Program name
tcp        0      0 127.0.0.1:55088         0.0.0.0:*               LISTEN      1974/python3
tcp        0      0 127.0.0.1:43825         0.0.0.0:*               LISTEN      2215187/python3
tcp        0      0 127.0.0.1:55026         0.0.0.0:*               LISTEN      2113/nodejs
tcp        0      0 127.0.0.1:5939          0.0.0.0:*               LISTEN      953/teamviewerd
tcp        0      0 0.0.0.0:6900            0.0.0.0:*               LISTEN      3432/php
tcp        0      0 0.0.0.0:6901            0.0.0.0:*               LISTEN      3201/php
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN      709/sshd: /usr/sbin
tcp        0      0 0.0.0.0:1880            0.0.0.0:*               LISTEN      596/node-red
tcp        0      0 127.0.0.1:35961         0.0.0.0:*               LISTEN      2157107/python3
tcp        0      0 192.168.31.125:8121     0.0.0.0:*               LISTEN      2164/nodejs
tcp        0      0 0.0.0.0:1883            0.0.0.0:*               LISTEN      702/mosquitto
tcp        0      0 127.0.0.1:55100         0.0.0.0:*               LISTEN      2286/python3
tcp        0      0 127.0.0.1:55008         0.0.0.0:*               LISTEN      1105388/python3
tcp        0      0 127.0.0.1:55012         0.0.0.0:*               LISTEN      2197/python3
tcp        0      0 0.0.0.0:8484            0.0.0.0:*               LISTEN      1989/deCONZ
tcp        0      0 127.0.0.1:3493          0.0.0.0:*               LISTEN      1548/upsd
tcp        0      0 192.168.31.125:3493     0.0.0.0:*               LISTEN      1548/upsd
tcp        0      0 127.0.0.1:55019         0.0.0.0:*               LISTEN      1103068/python3
tcp        0      0 127.0.0.1:55052         0.0.0.0:*               LISTEN      3247/python3
tcp        0      0 0.0.0.0:20877           0.0.0.0:*               LISTEN      1989/deCONZ
tcp6       0      0 :::80                   :::*                    LISTEN      728/apache2
tcp6       0      0 :::8020                 :::*                    LISTEN      2643/nodejs
tcp6       0      0 :::22                   :::*                    LISTEN      709/sshd: /usr/sbin
tcp6       0      0 :::443                  :::*                    LISTEN      728/apache2
tcp6       0      0 :::7845                 :::*                    LISTEN      596/node-red
tcp6       0      0 ::1:3493                :::*                    LISTEN      1548/upsd
tcp6       0      0 :::3306                 :::*                    LISTEN      798/mariadbd

Debian 11.4

Bad · Juillet 25, 2022, 6:59

Je ne vois franchement pas grand chose d’étrange, faudrait qu’on fasse un partage d’écran pour check.

Tu écoutes aussi sur le port 443, tu as Let’s Encrypt ?
Tu as une redirection du port 80 vers le port 443 ?

Tu confirmes que si tu va sur cette URL, tu restes bien en HTTP et qu’elle n’est pas convertie en HTTPS, si ?

regi54 · Juillet 25, 2022, 7:15

Oui l’url http://x.x.x.x.x:80/plugins/jMQTT/core/php/callback.php me donne bien Unauthorized access en HTTP
Pour let’s encrypt j’avais suivis le tuto https://doc.jeedom.com/fr_FR/howtoadvance/letsencrypt.mise_en_place après je ne sais pas t’en dire plus.
Si j’appel mon Jeedom en http, je ne suis pas renvoyé sur du HTTPS donc je ne pense pas avoir de redirection du port 80 vers le 443

regi54 · Juillet 25, 2022, 7:17

Je viens d’avoir un nouveau message dans les notification Jeedom:

`Date et heure	Source	Description	Action	Occurrences

2022-07-25 09:13:08	jMQTT	Accès non autorisé depuis 192.168.31.1 (pas de clé API)`

Sachant qu’à cette adresse c’est mon router

Bad · Juillet 25, 2022, 7:38

Ce n’est pas anormal, ça correspond à ce test que tu as effectué :

Tu dois probablement avoir du NAT sur ton routeur entre ta machine et Jeedom.

regi54 · Juillet 25, 2022, 8:59

ok,
Je sais pas s’il y a un lien mais quand je lance la vérification général j’ai cette erreur:

PHP Warning: PHP Startup: mosquitto: Unable to initialize module

Bad · Juillet 25, 2022, 9:56

Bon je pense que c’est plus lié à un autre plugin MQTT que tu as installé.

On utilise paho.mqtt.client dans jMQTT (dans le démon Python, pas en php)

regi54 · Juillet 25, 2022, 10:02

J’ai pas d’autre plugin MQTT, j’avais celui de Lunarok mais je l’ai désinstallé il y a un moment

Bad · Juillet 25, 2022, 10:10

Il doit rester des traces, mais ce n’est pas problématique.

L’erreur de lancement que tu rencontres est probablement liée à la une mauvaise identification du PID/port du démon par Jeedom lors du lancement du démon. Faut qu’on arrive à faire un debug en live en discord pour voir ça.

Jeandhom · Juillet 25, 2022, 12:03