JMQTT daemon en rade

Bonjour,

J’utilise le plugin pour interagir avec un switch shelly.

Tout a fonctionné pendant plusieurs semaines, mais maintenant ca bloque souvent.

Le daemon semble s’arreter.

[2024-04-03 09:22:13,321]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 105.182409ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:22:58,401]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:22:58,493]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 92.962980ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:23:25,834]INFO Client31        Brk31Th         on_message() : Message received (topic="shelly1minig3-ecda3bc207c0/debug/log", payload="shelly1minig3-ecda3bc207c0 20481 1712136205.879 1|shos_sntp.c:84          New SNTP time: 1712136205.803567 (delta -0.068033)
", QoS=0, retain=False)
[2024-04-03 09:23:25,834]DEBUG JMsg.Snd        Brk31Th         send_async() : Enqued the message in 0.006914ms (qToJ size 1): {'cmd': 'messageIn', 'id': '31', 'topic': 'shelly1minig3-ecda3bc207c0/debug/log', 'payload': 'shelly1minig3-ecda3bc207c0 20481 1712136205.879 1|shos_sntp.c:84          New SNTP time: 1712136205.803567 (delta -0.068033)
', 'qos': 0, 'retain': False}
[2024-04-03 09:23:25,845]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2024-04-03 09:23:25,881]INFO Client31        Brk31Th         on_message() : Message received (topic="shelly1minig3-ecda3bc207c0/debug/log", payload="shelly1minig3-ecda3bc207c0 20482 1712136205.808 1|shelly_sys.cpp:208      Time is set
", QoS=0, retain=False)
[2024-04-03 09:23:25,882]DEBUG JMsg.Snd        Brk31Th         send_async() : Enqued the message in 0.012398ms (qToJ size 1): {'cmd': 'messageIn', 'id': '31', 'topic': 'shelly1minig3-ecda3bc207c0/debug/log', 'payload': 'shelly1minig3-ecda3bc207c0 20482 1712136205.808 1|shelly_sys.cpp:208      Time is set
', 'qos': 0, 'retain': False}
[2024-04-03 09:23:25,903]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 57.363749ms (qToJ size 1): [{'cmd': 'messageIn', 'id': '31', 'topic': 'shelly1minig3-ecda3bc207c0/debug/log', 'payload': 'shelly1minig3-ecda3bc207c0 20481 1712136205.879 1|shos_sntp.c:84          New SNTP time: 1712136205.803567 (delta -0.068033)
', 'qos': 0, 'retain': False}]
[2024-04-03 09:23:25,905]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending 1 messages (0 left in queue)
[2024-04-03 09:23:25,959]DEBUG JMsg.Snd        SockOut               send() : Sent TO Jeedom 1 messages handled in 53.867817ms (qToJ size 0): [{'cmd': 'messageIn', 'id': '31', 'topic': 'shelly1minig3-ecda3bc207c0/debug/log', 'payload': 'shelly1minig3-ecda3bc207c0 20482 1712136205.808 1|shelly_sys.cpp:208      Time is set
', 'qos': 0, 'retain': False}]
[2024-04-03 09:24:11,035]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:24:11,129]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 95.227242ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:24:56,213]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:24:56,307]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 94.227552ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:25:41,392]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:25:41,486]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 94.706059ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:26:26,570]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:26:26,664]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 94.280005ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:27:11,748]DEBUG JMsg.Snd        SockOut           _loopSnd() : Sending a heartbeat to Jeedom, nothing sent since 45s (max 45s)
[2024-04-03 09:27:11,841]DEBUG JMsg.Snd        SndNoBlkHb            send() : Sent TO Jeedom 1 messages handled in 94.287872ms (qToJ size 0): [{'cmd': 'hb'}]
[2024-04-03 09:27:24,469]ERROR JMsg.Rcv        MainThread      is_working() : Nothing has been received for 315s (max 315s), Jeedom does not want me any longer.
[2024-04-03 09:27:24,570]INFO Main            MainThread        shutdown() : Stop jMQTT python daemon
[2024-04-03 09:27:24,571]DEBUG JMsg.Rcv        MainThread   receiver_stop() : Stop requested
[2024-04-03 09:27:24,832]INFO JMsg.Rcv        SockIn            _loopRcv() : Stopped
[2024-04-03 09:27:24,833]DEBUG JMsg.Rcv        MainThread   receiver_stop() : Stopped
[2024-04-03 09:27:24,834]DEBUG JMsg.Snd        Brk31Th         send_async() : Enqued the message in 0.008583ms (qToJ size 1): {'cmd': 'brokerDown', 'id': '31'}
[2024-04-03 09:27:24,835]INFO Client31        Brk31Th      on_disconnect() : Disconnected from broker.
[2024-04-03 09:27:24,836]DEBUG Client31        MainThread            stop() : jMqttClient ended
[2024-04-03 09:27:24,836]DEBUG Main            MainThread        shutdown() : Sent Daemon Down signal to Jeedom
[2024-04-03 09:27:24,837]DEBUG JMsg.Snd        MainThread     sender_stop() : Stop requested
[2024-04-03 09:27:24,870]INFO JMsg.Snd        SockOut           _loopSnd() : Stopped
[2024-04-03 09:27:24,871]DEBUG JMsg.Snd        MainThread     sender_stop() : Stopped
[2024-04-03 09:27:25,010]DEBUG JMsg.Snd        MainThread            send() : Sent TO Jeedom 1 messages handled in 138.605356ms (qToJ size 0): [{'cmd': 'daemonDown'}]
[2024-04-03 09:27:25,011]DEBUG root            MainThread        () : Exit 0

Quand je redémarre le daemon ca repart pour un tour.

Any ideas ?

Merci !!

Hello,

Tu es sur quelle version du plugin et de Jeedom ? + Pages santé

Tu as des erreurs dans les autres fichiers de log ou dans le log cron ?

Des choses ou changements notables sur ton installation ?

Bad

Version du plugin : v23.11.6
Page santé (les erreurs ne sont pas liées à JMQTT):

Quand ca ne fonctionne plus, le daemon est en NOK.
Je restart ca repart.
Avant ca avait fonctionné des semaines. Ensuite, ca a commencé à se dégrader. Et maintenant ca bug au bout de quelques minutes / heures.

J’ai eu aussi un problème similaire où le démon ne redémarre pas automatiquement, pas de solution actuellement ((Re)démarrage auto du démon - #21 par defmy), peut-être voir ce qu’on a de commun.

Hello @gnarco, @defmy,

Je n’arrive pas à reproduire le problème (en 4.3 ou en 4.4), mais visiblement vous oui.
Il y a probablement un crash dans le daemon lors de la réception de certaines informations en MQTT (ce qui semble étrange, vu que j’avais blindé de points de contrôles).

Pouvez-vous tous les 2 me faire un Message Privé, avec :

  • soit un compte pour accéder en admin à votre Jeedom (on peut aussi faire une session Discord pour regarder ensemble),

  • soit une sauvegarde de votre config jMQTT (1), la télécharger (2) et me l’envoyer en privé ?

Merci,
Bad