Deamon en erreur toutes les heures

Plugins Monitoring
1

Bonjour à tous,
Tout d’abord un grand merci pour votre suivi et votre implication. Je n’utilise pas pleinement le plugin mais je suis impressionné par votre réactivité !
Voici mon pbme :

  • J’ai un scénario qui tourne toutes les 5min et qui me remonte les daemons en erreur.
  • Depuis quelques jours, toutes les heures à xxh40, le scénario m’informe que le deamon freebox_os est en erreur.
  • En vérifiant le deamon ne reste pas dans cet état mais est bien relancé avec succès jusqu’à l’heure suivante
  • En cherchant du niveau de logs, j’ai l’impression qu’il tombe en erreur suite à un problème d’authentification
  • Je suis en 4.2.5 (freebox server) et en stable pour le plugin : j’en déduis normalement que le soucis d’authentification dont il est fait mention dans différents sujets et résolu

D’où ma question : est-il normal que le deamon tombe en erreur toutes les heures en raison du soucis d’authentification (avec une relance auto) ?
Y voyez vous une autre explication ?
Merci !

2

Lo,

Peux tu nous partager les logs du plugin en debug pour comprendre !
Une bêta semble avoir corrigé cette erreur,
Cette erreur remonte si ton équipement Appareils connectés est actif,
il y a un trop grand nombre de requêtes…

Au plaisir, bonne journée,

2 « J'aime »
3

Voici ce que j’ai (cf. dessous).
Je n’ai pas activé « Appareils connectés »
A 17h40 mon scénario détecte que le deamon Freebox_OS est ko et me notifie (ce qui signifie que le deamon est tombé en erreur entre 17:35:00 et 17:39:59).
Si je me connecte dans la foulée, le deamon est bien redémarré tout seul (peut être avec le cron suivant de Freebox_OS)

[2020-09-29 17:35:04][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés ==================
[2020-09-29 17:35:04][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés Wifi Invité ==================
[2020-09-29 17:35:04][DEBUG] : ================= CRON pour l'actualisation de : Freebox Débits ==================
[2020-09-29 17:35:04][DEBUG] : ┌───────── Début de Mise à jour
[2020-09-29 17:35:04][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/(null)
[2020-09-29 17:35:05][DEBUG] : │ [Freebox Request Result] : {"uid":"XXX","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"XXX","challenge":"XXX"},"error_code":"auth_required"}
[2020-09-29 17:35:05][DEBUG] : [Redémarrage session à cause de l'erreur] : auth_required
[2020-09-29 17:35:05][DEBUG] : [Freebox Close Session] : {"uid":"XXX","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"XXX","challenge":"XXX"},"error_code":"invalid_session"}
[2020-09-29 17:35:05][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"XXX","password_salt":"XXX","password_set":true}}
[2020-09-29 17:35:05][DEBUG] : [get Freebox Open Session Data] : {"result":{"session_token":"XXX","challenge":"XXX","password_salt":"XXX","permissions":{"parental":false,"contacts":true,"explorer":true,"tv":true,"wdo":true,"downloader":true,"profile":true,"camera":true,"settings":true,"calls":true,"home":true,"pvr":true,"vm":true,"player":true},"password_set":true},"success":true}
[2020-09-29 17:35:05][DEBUG] : [Redémarrage session Terminée à cause de l'erreur] : auth_required
[2020-09-29 17:35:05][DEBUG] : ================= CRON pour l'actualisation de : Freebox Player ==================
[2020-09-29 17:35:05][DEBUG] : ================= CRON pour l'actualisation de : Mattéo ==================
[2020-09-29 17:40:06][DEBUG] : ================= CRON pour l'actualisation de : Air Média ==================
[2020-09-29 17:40:06][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés ==================
[2020-09-29 17:40:06][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés Wifi Invité ==================
[2020-09-29 17:40:06][DEBUG] : ================= CRON pour l'actualisation de : Freebox Débits ==================
[2020-09-29 17:40:06][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"XXX","password_salt":"XXX","password_set":true}}
[2020-09-29 17:40:06][DEBUG] : [Freebox Open Session] : {"result":{"session_token":"XXX","challenge":"XXX","password_salt":"XXX","permissions":{"parental":false,"contacts":true,"explorer":true,"tv":true,"wdo":true,"downloader":true,"profile":true,"camera":true,"settings":true,"calls":true,"home":true,"pvr":true,"vm":true,"player":true},"password_set":true},"success":true}
[2020-09-29 17:40:07][DEBUG] : ┌───────── Début de Mise à jour
[2020-09-29 17:40:07][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.254/api/v8/connection/(null)
[2020-09-29 17:40:07][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{"type":"ethernet","rate_down":9040,"bytes_up":475813129,"ipv4_port_range":[0,65535],"rate_up":3950,"bandwidth_up":700000000,"ipv6":"XXX","bandwidth_down":1000000000,"media":"ftth","state":"up","bytes_down":3696920835,"ipv4":"XXX"}}
[2020-09-29 17:40:07][DEBUG] : └─────────
4

Bonjour,
Après quelques vérifications :

  • le deamon Freebox_Os tombe bien en erreur à XX:35:0X, cela semble correspondre à la log que j’ai mentionnée plus haut
  • mon scenario de vérification détecte cette erreur à XX:40:00 et me la signale
  • le cron Freebox_Os qui tourne à XX:40:05 semble relancer le deamon
6

Peux-tu passer en bêta et remonter si problème il y a,
Merci,

7

Bonjour,

La stable ne devrai pas tarder juste le temps que le change log soit mis à jour donc si tu peux attendre.

8

Hello,
Pour information

C’est normal que le demon tombe en cas de problème d’authentification dans le code je dois l’arrêter puis le redémarrer pour pouvoir récupérer les informations.

Par contre, c’est le firmware de la Freebox qui pose problème avec la dernière mise à jour.
Je suis en train dans les versions bêta de limiter de nombre de requête pour essayer de palier a ce problème mais cela n’est pas évident.

La stable ne devrait pas tarder comme la dit @iPapy. C’est la partie documentation a terminée

1 « J'aime »
9

Hello JAG,
Je suis passé en beta et le soucis est toujours présent.
Je me suis douté que le pbme d’authentification était à l’origine de l’arrêt du deamon. Par contre n’est-il pas possible de le relancer dans la foulée et éviter d’attendre l’exécution du cron 5min plus tard ?

10

Hello
tu ne dois pas avoir le demon en automatique.

Capture
Capture1202×143 7.66 KB

c’est automatiquement relancé par le code dans la foulée mais il y a quelques secondes d’écart le temps de reprendre l’authentification.
Mais d’après tes logs, il y bien redémarré l’authentification

Tu peux passer en stable car la stable a été mise à jour ce matin

1 « J'aime »
11

Hello,
Passage en stable à l’instant, je vous tiens au courant.
Par contre j’avais bien vérifié, mon deamon était en auto, l’authentification semblait bien se refaire à XX:35:0x mais le deamon restait en KO pendant 5 min.
Pour relancer le deamon tu te bases sur la gestion automatique ou tu force la relance ?

J’imagine que la gestion auto du deamon ne tourne pas en continu, si elle tourne toutes les 5 minutes cela pourrait expliquer que le deamon reste KO 5 minutes toutes les heures, entre le moment où tu l’arrêtes et le moment ou la gestion auto le relance.

12

IL faut avoir la gestion automatique du demon active. si non je ne peux le relancer

13

Bon je confirme, même avec la dernière version stable il y a toujours un problème de deamon KO pendant 5min toutes les heures.
Suis-je le seul concerné ou est-ce « by design » ?

14

J’ai les deux versions du plugin stable et bêta. Je ne rencontre aucun problème de mon côté pour le moment

15

donnes les log en mode debug

ainsi que la copie d’ecran de la configuration du demon

16

Je viens de vérifier en live :

  • A 11:35:0X, le deamon passe en KO
  • Il reste KO pendant 5 minutes
  • A 11:40:0X, le deamon passe en OK

Voila pour la configuration du plugin.

image
image1482×560 38.1 KB

Voici les dernières logs du plugin :

[2020-10-01 11:35:05][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés Wifi Invité ==================
[2020-10-01 11:35:05][DEBUG] : ================= CRON pour l'actualisation de : Freebox Débits ==================
[2020-10-01 11:35:05][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:35:05][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/(null)
[2020-10-01 11:35:05][DEBUG] : │ [Freebox Request Result] : {"uid":"XXX","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"ZZZ","challenge":"YYY"},"error_code":"auth_required"}
[2020-10-01 11:35:05][DEBUG] : [Redémarrage session à cause de l'erreur] : auth_required
[2020-10-01 11:35:05][DEBUG] : [Freebox Close Session] : {"uid":"XXX","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"ZZZ","challenge":"YYY"},"error_code":"invalid_session"}
[2020-10-01 11:35:05][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"YYY","password_salt":"ZZZ","password_set":true}}
[2020-10-01 11:35:06][DEBUG] : [get Freebox Open Session Data] : {"result":{"session_token":"TOKEN","challenge":"YYY","password_salt":"ZZZ","permissions":{"parental":false,"contacts":true,"explorer":true,"tv":true,"wdo":true,"downloader":true,"profile":true,"camera":true,"settings":true,"calls":true,"home":true,"pvr":true,"vm":true,"player":true},"password_set":true},"success":true}
[2020-10-01 11:35:06][DEBUG] : [Redémarrage session Terminée à cause de l'erreur] : auth_required
[2020-10-01 11:35:06][DEBUG] : ================= CRON pour l'actualisation de : Freebox Player ==================
[2020-10-01 11:35:06][DEBUG] : ================= CRON pour l'actualisation de : Contrôle ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Air Média à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés Wifi Invité à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Disque Dur à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Débits à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Player à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Mattéo à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Système à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléchargements à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléphone à cause du Démon : nok ==================
[2020-10-01 11:39:04][DEBUG] : ================= PAS DE CRON pour d'actualisation Wifi à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Air Média à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés Wifi Invité à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Disque Dur à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Débits à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Player à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Contrôle à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Système à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléchargements à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléphone à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Wifi à cause du Démon : nok ==================
[2020-10-01 11:40:05][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"YYY2","password_salt":"ZZZ","password_set":true}}
[2020-10-01 11:40:06][DEBUG] : [Freebox Open Session] : {"result":{"session_token":"TOKEN2","challenge":"YYY2","password_salt":"ZZZ","permissions":{"parental":false,"contacts":true,"explorer":true,"tv":true,"wdo":true,"downloader":true,"profile":true,"camera":true,"settings":true,"calls":true,"home":true,"pvr":true,"vm":true,"player":true},"password_set":true},"success":true}
[2020-10-01 11:43:24][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:43:24][DEBUG] : │ [Freebox Request Connexion] :  sur la l'adresse 192.168.0.XXX/api/v8/airmedia/receivers/(null)
[2020-10-01 11:43:24][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":[{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Server","password_protected":false},{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Player","password_protected":false}]}
[2020-10-01 11:43:24][DEBUG] : └─────────
[2020-10-01 11:43:25][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:43:25][DEBUG] : │ [Freebox Request Connexion] :  sur la l'adresse 192.168.0.XXX/api/v8/airmedia/receivers/(null)
[2020-10-01 11:43:25][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":[{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Server","password_protected":false},{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Player","password_protected":false}]}
[2020-10-01 11:43:25][DEBUG] : └─────────
[2020-10-01 11:43:25][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:43:25][DEBUG] : │ [Freebox Request Connexion] :  sur la l'adresse 192.168.0.XXX/api/v8/airmedia/receivers/(null)
[2020-10-01 11:43:25][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":[{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Server","password_protected":false},{"capabilities":{"photo":false,"screen":false,"audio":true,"video":false},"name":"Freebox Player","password_protected":false}]}
[2020-10-01 11:43:25][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Air Média ==================
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés ==================
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés Wifi Invité ==================
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Freebox Débits ==================
[2020-10-01 11:45:06][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/(null)
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{RESULT}}
[2020-10-01 11:45:06][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/config(null)
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{RESULT}}
[2020-10-01 11:45:06][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/ftth(null)
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{RESULT}}
[2020-10-01 11:45:06][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/connection/xdsl(null)
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{RESULT}}
[2020-10-01 11:45:06][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Freebox Player ==================
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Contrôle ==================
[2020-10-01 11:45:06][DEBUG] : ┌───────── Début de Mise à jour
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse 192.168.0.XXX/api/v8/network_control/1(null)
[2020-10-01 11:45:06][DEBUG] : │ [Freebox Request Result] : {"success":true,"result":{RESULT}}
[2020-10-01 11:45:06][DEBUG] : └─────────
[2020-10-01 11:45:06][DEBUG] : >───────── Etat Contrôle Parental : /1
[2020-10-01 11:45:06][DEBUG] : ================= CRON pour l'actualisation de : Wifi ==================
17

Il rédemarre a la prochaine requête.

Je vais essayer de redémarrer le demon quand je vois le message. mais je ne promet rien

18

Ok, pour le moment c’est du « by design ».
Si moyen de le redémarrer c’est top, sinon il faudra s’accommoder de ces 4% d’indisponibilité.
Perso ça ne me gène pas trop (utilisation limitée) je peux contourner en excluant le deamon de mon scenario de suivi mais pour d’autres c’est peut être plus structurant.

19

Vu la méthode de fonctionnement, il n’y a pas d’impact.

C’est le plugin qui interroge la freebox pas l’inverse.
Donc même si le démon est arrêté entre 2 interrogations il n’y a pas de trou dans les données.

20

Il n’y a pas d’impact à la récupération de données mais j’imagine que si on se met à utiliser le plugin pour interagir avec des équipements c’est autre chose.
Je ne sais pas ce que permet de faire le plugin exactement avec une Freebox Delta + pack sécurité mais imaginons que tous les matins je me lève à 6h30 et qu’en descendant 10’ plus tard au RdC je souhaite désactiver l’alarme. Le deamon étant KO toutes les heures entre xx:35 et xx:40 (dans mon cas), aucun de mes scénarios qui interagissent avec les équipement ne vont fonctionner (par ex. sur détection de l’ouverture de mes volets le matin je veux désactiver l’alarme).

21

Comme dit par @JAG, ce n’est pas toutes les 5 minutes mais jusqu’à la prochaine requête.

Donc si tu lance une action ça redémarre le démon Puisqu’il va faire une requête à la Freebox. Ou alors j’ai mal compris sa réponse.