Deamon en erreur toutes les heures

[JAG]

donnes les log en mode debug

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

[Arsach]

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.

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 ==================

[JAG]

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

[Arsach]

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.

[Poumi]

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.

[Arsach]

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).

[Poumi]

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.

[JAG]

c’est bien cela
il fera la mise a jour de l’authentification

Je vais essayer de faire qq chose mais c’est compliqué avec Free qui fait evolué son firmware avec autant de bugs

[Arsach]

Ok merci pour votre retour.
En attendant je vais adapter ma surveillance pour ignorer le KO du deamon.

[Ultraboss]

Bonsoir,
c’est justement ce que je monitore via les timeouts des équipements jeedom, et je peux assurer que quand il y a réauthentification, on perd 1 occurrence d’acualisation sur l’équipement, Débits par ex.
Avec un timeout à 6min, ça se voit très clairement quand l’authent saute…

Problème ID qui change état des appareils connectés

Vous avez une idée de ce qui provoque ces pertes de sessions ? Il me semble que c’était l’erreur qui arrivait depuis la dernière version du serveur Freebox. Je suppose que la perte de session affecte aléatoirement certains rafraîchissement, comme l’équipement débits, car j’ai une surveillance de timeout à 6min dessus (basé sur la date last communication) et j’ai parfois des alertes. Donc pour moi c’est la perte de session qui bloque les refresh. Ne faudrait il pas prévoir de refaire un refresh f…

[Ultraboss]

Voici ma log :

[2020-09-27 20:35:05][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés ==================
[2020-09-27 20:35:05][DEBUG] : ┌───────── Début de Mise à jour
[2020-09-27 20:35:05][DEBUG] : │ [Freebox Request Connexion] : GET sur la l'adresse mafreebox.freebox.fr/api/v8/lan/browser/pub(null)
[2020-09-27 20:35:05][DEBUG] : │ [Freebox Request Result] : {"uid":"blablablabla","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"ZZZZZZZZZZZZZZZZZZZZZZZZZ","challenge":"ZZZZZZZZZZZZZZZZZZZZZZZ"},"error_code":"auth_required"}
[2020-09-27 20:35:05][DEBUG] : [Redémarrage session à cause de l'erreur] : auth_required
[2020-09-27 20:35:05][DEBUG] : [Freebox Close Session] : {"uid":"blablablabla","success":false,"msg":"Vous devez vous connecter pour accéder à cette fonction","result":{"password_salt":"XXXXXXXXXXXXXXXXXXXXX","challenge":"ZZZZZZZZZZZZZZZZZZZZZZZ"},"error_code":"invalid_session"}
[2020-09-27 20:35:05][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"ZZZZZZZZZZZZZZZZZZZZZZZZZ","password_salt":"ZZZZZZZZZZZZZZZZZZZZZZZ","password_set":true}}
[2020-09-27 20:35:05][DEBUG] : [get Freebox Open Session Data] : {"result":{"session_token":"XXXXXXXXXXXXXXXXXXXXX","challenge":"XXXXXXXXXXXXXXXXXXXXX","password_salt":"ZZZZZZZZZZZZZZZZZZZZZZZZZ","permissions":{"parental":true,"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-27 20:35:05][DEBUG] : [Redémarrage session Terminée à cause de l'erreur] : auth_required
[2020-09-27 20:35:05][DEBUG] : │===========> RESULT Requête pas correct :
[2020-09-27 20:35:05][DEBUG] : ================= CRON pour l'actualisation de : Appareils connectés Wifi Invité ==================
[2020-09-27 20:35:05][DEBUG] : ================= CRON pour l'actualisation de : Freebox Débits ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Nexus 7 à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux Alice à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux Camille à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux parents à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Air Média à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés Wifi Invité à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Disque Dur à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Débits à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Player à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Home Adapters à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Système à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléchargements à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléphone à cause du Démon : nok ==================
[2020-09-27 20:39:03][DEBUG] : ================= PAS DE CRON pour d'actualisation Wifi à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Nexus 7 à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux Alice à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux Camille à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Volet velux parents à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Air Média à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Appareils connectés Wifi Invité à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Disque Dur à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Débits à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Freebox Player à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Home Adapters à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Système à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléchargements à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Téléphone à cause du Démon : nok ==================
[2020-09-27 20:40:05][DEBUG] : ================= PAS DE CRON pour d'actualisation Wifi à cause du Démon : nok ==================
[2020-09-27 20:40:08][DEBUG] : [Freebox Password] : {"success":true,"result":{"logged_in":false,"challenge":"XXXXXXXXXXXXXXXXXXXXX","password_salt":"ZZZZZZZZZZZZZZZZZZZZZZZ","password_set":true}}
[2020-09-27 20:40:08][DEBUG] : [Freebox Open Session] : {"result":{"session_token":"ZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZZ","challenge":"ZZZZZZZZZZZZZZZZZZZZZZZZZ","password_salt":"XXXXXXXXXXXXXXXXXXXXX","permissions":{"parental":true,"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-27 20:45:06][DEBUG] : ================= CRON pour l'actualisation de : Nexus 7 ==================

J’ai eu l’alerte de timeout à 20h41, qui prouve bien que le refresh de 20h40 n’a pas été fait malgré l’indication de la log.

[JAG]

hello

je sais ce qui se passe,

Je suis en train de faire quelques choses déjà pour redémarrer automatiquement l’authentification pour ne pas perdre les crons suivants

Pour le cron ou il y a eu l’erreur c’est plus compliqué a gérer.

[Ultraboss]

Bonsoir JAG,
j’imagine que lorsque l’auth tombe, il faudrait relancer l’auth + reprendre les cron qui n’ont pas pu être faits, voir tous les relancer…

[JAG]

@Ultraboss

j’imagine que lorsque l’auth tombe, il faudrait relancer l’auth + reprendre les cron qui n’ont pas pu être faits, voir tous les relancer…

Pour les Crons suivants, je suis en train de tester qq chose.

Mais c’est plus compliqué pour gérer le refresh ou il y a l’erreur

[Ultraboss]

C’est quoi qui grille au juste la session ? un nombre prédéterminé de requêtes ou un délai d’expiration ??

[JAG]

il faut demander a Free, car c’est depuis le dernier firmware.
Ils ont dût limiter le nombre de requête c’est pour cela que j’essaye de simplifier les mises à jour

[JAG]

J’ai fait des améliorations dans la prochaine beta

Mais là c’est le firmware de la Freebox qui pose pb

Info Plugin Freebox : Nouveautés

BETA du 05/10/2020 Disque Dur : Disque Dur Prise en compte des améliorations de @mid.sebastien Infos disques sur config RAID 1 Attention, il faut faire une correction manuelle des réglages si vous ne supprimer pas les disques [Capture d’écran 2020-10-04 à 12.04.57] Optimisation Freebox Débit : Optimisation du nombre de requête Amélioration refresh Token pour palier au nouveau Firmware de la Freebox Corrections de variables non défini pour la partie Tiles Bon dimanche

[Arsach]

Au top, je teste d’ici ce soir.
Merci JAG

[Arsach]

So far so good, le deamon ne tombe plus en erreur toutes les heures (je suis en beta).
Merci pour le support et la réactivité !