Hkcontrol et erreurs jeeEvent vous n’êtes pas autorisé …

blablatus · Février 8, 2022, 8:49

Bonsoir.

J’ai passé ma jeedom de prod en v4.2.9 dans la journée. Tout s’est très bien passé, sauf l’apparition de messages d’erreur type « Vous n’êtes pas autorisé à effectuer cette action » dans « jeeEvent.log ». J’ai vérifié tous mes appels à l’api jeedom et ce ne sont pas ces appels qui provoquent ces erreurs. J’ai cherché et j’ai trouvé que les erreurs apparaissent à l’ouverture et à la fermeture de mes volets. Ces volets sont pilotés via un pont idiamant qui est appairé à hkcontrol.
Voici les logs :

Log jeeEvent

[2022-02-08 21:20:47][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-08 21:20:52][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-08 21:21:26][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-08 21:21:31][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52

Log hkControl

[2022-02-08 21:20:41][INFO] : Action à envoyer au démon : Position.target (Ouvrir)(D5:13:F7:7B:CF:BB)(8.10)->100
[2022-02-08 21:20:42][DEBUG] : SetAccessories brut : {"result":"ok"}
[2022-02-08 21:21:20][INFO] : Action à envoyer au démon : Position.target (Fermer)(D5:13:F7:7B:CF:BB)(8.10)->0
[2022-02-08 21:21:21][DEBUG] : SetAccessories brut : {"result":"ok"}

Log hkControl_daemon

[08-02-2022 21:20:41][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"100"}
[08-02-2022 21:20:42][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->100
[08-02-2022 21:20:42][INFO] : Event reçu de Bubendorff Gateway : position.target=100
[08-02-2022 21:20:47][INFO] : Event reçu de Bubendorff Gateway : position.current=100
[08-02-2022 21:21:20][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"0"}
[08-02-2022 21:21:21][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->0
[08-02-2022 21:21:21][INFO] : Event reçu de Bubendorff Gateway : position.target=0
[08-02-2022 21:21:24][INFO] : Event reçu de Bubendorff Gateway : position.current=0

Dans cette dernière log, on y voit 4 « Event » qui semblent correspondre à ce qu’il y a dans la log « jeeEvent ».

@nebz pourriez-vous y regarder et me donner votre avis, svp ?
Par avance, merci.

nebz · Février 8, 2022, 9:11

Non je ne vois aucun problème, pour moi c’est pas lié… surtout que je suis sur 4.2 depuis des mois et personne d’autre n’a relevé ce problème sur ce plugin.

Les erreurs s’afficheraient dans le log demon si ça venait de la.

Et à part 1 event sur les 4, les autres ne sont même pas à la même heure.

Et surtout ça voudrait dire que tu aurais des problèmes de remontées de valeur entre le demon et jeedom !

Tu peux activer le log api ou event et voir si ça correspond

Ou encore mieux désactiver le plugin pour tester !

blablatus · Février 9, 2022, 7:16

Bonjour.
J’ai activé dans la foulée les log api et event et rien ne correspond. Je vais laisser tourner plusieurs jours pour confirmer ou infirmer le fait que ces erreurs soient liée à l’ouverture et la fermeture de mes volets.
Ouverture de mes volets ce matin, 5 event dans la log hkcontrol_daemon et 5 messages d’erreur dans la log jeeEvent. A suivre.
Merci.

nebz · Février 9, 2022, 7:21

le seul moyen que j’ai trouvé de créer des messages d’erreurs dans jeeEvent, c’est de passer l’api de hkControl à « désactivée » mais je suppose que tu as déjà vérifié ce point

blablatus · Février 9, 2022, 9:00

Je n’ai pas vérifié ce point : je ne souhaite pas désactiver le plugin, comme cela était indiqué

Je vais désactiver l’api de hkcontrol et refaire des tests. Merci.

blablatus · Février 9, 2022, 9:49

Voilà tests réalisés, fermeture puis ouverture d’un volet.

avec les logs :

jeeEvent

[2022-02-09 09:59:49][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-09 09:59:54][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-09 10:01:40][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52
[2022-02-09 10:01:45][ERROR] : Vous n'êtes pas autorisé à effectuer cette action, IP : 192.168.1.52

hkcontrol

[2022-02-09 09:59:44][INFO] : Action à envoyer au démon : Position.target (Fermer)(D5:13:F7:7B:CF:BB)(8.10)->0
[2022-02-09 09:59:44][DEBUG] : SetAccessories brut : {"result":"ok"}
[2022-02-09 10:01:35][INFO] : Action à envoyer au démon : Position.target (Ouvrir)(D5:13:F7:7B:CF:BB)(8.10)->100
[2022-02-09 10:01:35][DEBUG] : SetAccessories brut : {"result":"ok"}

hkcontrol_daemon

[09-02-2022 09:59:44][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"0"}
[09-02-2022 09:59:44][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->0
[09-02-2022 09:59:44][INFO] : Event reçu de Bubendorff Gateway : position.target=0
[09-02-2022 09:59:47][INFO] : Event reçu de Bubendorff Gateway : position.current=0
[09-02-2022 10:01:35][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"100"}
[09-02-2022 10:01:35][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->100
[09-02-2022 10:01:35][INFO] : Event reçu de Bubendorff Gateway : position.target=100
[09-02-2022 10:01:38][INFO] : Event reçu de Bubendorff Gateway : position.current=100

A priori, ça ne donne pas plus d’infos.
Sauf erreur de compréhension de ma part, les events reçus de Bubendorff Gateway mettent à jour la valeur des infos « position.target » et « position.current » à 100 à l’ouverture (même si ces 2 infos sont déclarées de type « binaire », le 100 est converti en 1) et à zéro à la fermeture.
Si je regarde l’état actuel des ces 2 infos pour le volet sur lequel je fais mes tests (volet qui est fermé au moment où j’écris), on constate ceci :

Je m’attends à y trouver zéro. Les valeurs sont celles d’un volet ouvert et la date de dernière mise à jour de ces infos est l’ouverture du volet hier matin avant mon passage en 4.2.9.
J’ai en tout 13 volets. Sur ces 13 volets, 10 sont utilisés tous les jours et 3 restent actuellement fermés. Pour les 10 volets, la date de dernière mise à jour des 2 infos est leur ouverture d’hier matin (toutes à 1). Pour les 3 volets utilisés occasionnellement (ils sont fermés), la date de mise à jour est le 19 novembre dernier et les valeurs sont à zéro (ce qui est ok).
Depuis le passage en 4.2.9, les infos « position.target » et « position.current » semblent donc ne plus être mises à jour.
Voilà l’état de mes analyses.
Reste à disposition pour faire d’autres tests.
Merci.

nebz · Février 9, 2022, 12:06

Non c’était justement la laisser activée !! Je disais juste que je reproduisais votre problème si elle est désactivé (ce qu’il ne faut pas faire évidemment !!!)

nebz · Février 9, 2022, 12:11

Pouvez-vous passer en beta, j’ai ajouté des logs pour indiquer exactement ce qu’on envoi et ce qu’on recois de jeedom., fournissez le log hkControl_daemon

blablatus · Février 9, 2022, 12:14

Pas de risque de perdre mes équipements actuels ?

nebz · Février 9, 2022, 12:16

non pas de risque (tant que vous désinstallez pas !, il faut juste installer par dessus)

blablatus · Février 9, 2022, 12:29

Beta installée et tests effectués.

Log hkControl_daemon (fermeture, puis ouverture volet)



[09-02-2022 13:18:18][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"0"}
[09-02-2022 13:18:18][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->0
[09-02-2022 13:18:18][INFO] : Event reçu de Bubendorff Gateway : position.target=0
Ajout du message {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.10","value":0,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0} dans la queue des messages a transmettre a Jeedom
Nombre de messages en attente de traitement : 1
Traitement du message : {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.10","value":0,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0}
Response from Jeedom: 200
Full Response: {"statusCode":200,"body":"","headers":{"date":"Wed, 09 Feb 2022 12:18:18 GMT","server":"Apache","access-control-allow-origin":"*","content-length":"0","connection":"close","content-type":"text/html; charset=UTF-8"},"request":{"uri":{"protocol":"http:","slashes":true,"auth":null,"host":"192.168.1.52","port":80,"hostname":"192.168.1.52","hash":null,"search":null,"query":null,"pathname":"/core/api/jeeApi.php","path":"/core/api/jeeApi.php","href":"http://192.168.1.52/core/api/jeeApi.php"},"method":"POST","headers":{"content-type":"application/x-www-form-urlencoded","content-length":140}}}
Nombre de messages en attente de traitement : 0
[09-02-2022 13:18:23][INFO] : Event reçu de Bubendorff Gateway : position.current=0
Ajout du message {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.11","value":0,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0} dans la queue des messages a transmettre a Jeedom
Nombre de messages en attente de traitement : 1
Traitement du message : {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.11","value":0,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0}
Response from Jeedom: 200
Full Response: {"statusCode":200,"body":"","headers":{"date":"Wed, 09 Feb 2022 12:18:23 GMT","server":"Apache","access-control-allow-origin":"*","content-length":"0","connection":"close","content-type":"text/html; charset=UTF-8"},"request":{"uri":{"protocol":"http:","slashes":true,"auth":null,"host":"192.168.1.52","port":80,"hostname":"192.168.1.52","hash":null,"search":null,"query":null,"pathname":"/core/api/jeeApi.php","path":"/core/api/jeeApi.php","href":"http://192.168.1.52/core/api/jeeApi.php"},"method":"POST","headers":{"content-type":"application/x-www-form-urlencoded","content-length":140}}}
Nombre de messages en attente de traitement : 0
[09-02-2022 13:20:02][INFO] : Reçu une demande d'action...{"id":"D5:13:F7:7B:CF:BB","aid":"8","iid":"10","val":"100"}
[09-02-2022 13:20:02][INFO] : Action de jeedom effectuée pour Bubendorff Gateway : position.target->100
[09-02-2022 13:20:02][INFO] : Event reçu de Bubendorff Gateway : position.target=100
Ajout du message {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.10","value":100,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0} dans la queue des messages a transmettre a Jeedom
Nombre de messages en attente de traitement : 1
Traitement du message : {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.10","value":100,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0}
Response from Jeedom: 200
Full Response: {"statusCode":200,"body":"","headers":{"date":"Wed, 09 Feb 2022 12:20:02 GMT","server":"Apache","access-control-allow-origin":"*","content-length":"0","connection":"close","content-type":"text/html; charset=UTF-8"},"request":{"uri":{"protocol":"http:","slashes":true,"auth":null,"host":"192.168.1.52","port":80,"hostname":"192.168.1.52","hash":null,"search":null,"query":null,"pathname":"/core/api/jeeApi.php","path":"/core/api/jeeApi.php","href":"http://192.168.1.52/core/api/jeeApi.php"},"method":"POST","headers":{"content-type":"application/x-www-form-urlencoded","content-length":142}}}
Nombre de messages en attente de traitement : 0
[09-02-2022 13:20:04][INFO] : Event reçu de Bubendorff Gateway : position.current=100
Ajout du message {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.11","value":100,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0} dans la queue des messages a transmettre a Jeedom
Nombre de messages en attente de traitement : 1
Traitement du message : {"data":{"eventType":"updateValue","id":"D5:13:F7:7B:CF:BB","aidiid":"8.11","value":100,"type":"event","apikey":"x1jNnTbtPVMjkS4s10JL2BmfsEb9tdxH","plugin":"hkControl"},"tryCount":0}
Response from Jeedom: 200
Full Response: {"statusCode":200,"body":"","headers":{"date":"Wed, 09 Feb 2022 12:20:04 GMT","server":"Apache","access-control-allow-origin":"*","content-length":"0","connection":"close","content-type":"text/html; charset=UTF-8"},"request":{"uri":{"protocol":"http:","slashes":true,"auth":null,"host":"192.168.1.52","port":80,"hostname":"192.168.1.52","hash":null,"search":null,"query":null,"pathname":"/core/api/jeeApi.php","path":"/core/api/jeeApi.php","href":"http://192.168.1.52/core/api/jeeApi.php"},"method":"POST","headers":{"content-type":"application/x-www-form-urlencoded","content-length":142}}}
Nombre de messages en attente de traitement : 0

Bonnes nouvelles avec cette version :

plus de message d’erreur dans « jeeEvent.log »
mise à jour « normale » des infos « position.target » et « position.current ».

Voilà.
Merci.

nebz · Février 9, 2022, 12:45

Rien n’a été fait pour ça… ou alors c’est une modification découlant d’autres modifications …

nebz · Février 9, 2022, 12:45

Ou simplement le fait de relancer le démon, mais je suppose que vous aviez déjà testé ???

nebz · Février 9, 2022, 12:47

Pouvez vous tester en opérant plus de volets en même temps ?

blablatus · Février 9, 2022, 12:48

Heu, honnêtement, je n’ai pas l’action de relancer le daemon après le passage en 4.2. Avec l’install de la beta, j’ai forcé le lancement du daemon.

nebz · Février 9, 2022, 12:49

La mise à jour vers 4.2 a stoppé tous les démons normalement

blablatus · Février 9, 2022, 1:12

Fermeture puis ouverture d’un groupe de volets : pas de problème, ça fonctionne.

nebz · Février 9, 2022, 1:56

Vous pouvez repasser en stable pour confirmer ?

blablatus · Février 9, 2022, 2:42

C’est fait et ça fonctionne toujours sans erreur, même si je comprends pas tout car la version stable réinstallée date du 16/11/2021.

nebz · Février 9, 2022, 2:43

oui, la stable n’a pas bougé depuis.