Erreur plugin Alexaapi

Jenjen · Avril 21, 2021, 8:59

Ca a planté ce matin (fichier alexaapi_node), quand je dis planté c’est que Alexa ne parle plus.
Voila les 2 premières lignes du fichier, donc les premières erreurs. Ensuite on a les « traditionnelles » « Alexa-API: Error: no body Commands ».

[4/21/2021, 07:11:32][] : Alexa-Remote: Activity for id 1618985485711#A3FX4UWTP28V1P#G2A14Q04011503CU not found
[4/21/2021, 07:17:54][ERROR] : Alexa-API: Error: no JSON Commands: [{"command":"volume","value":"60"},{"command":"speak","value":"Le portail s'ouvre."},{"command":"volume","value":"60"}] Query: {"text":"Le portail s'ouvre.","volume":"60","lastvolume":"60","device":"G2A14Q04011503CU","value":"Le portail s'ouvre."}

J’ai mis à jour ta version d’hier du plugin.

Uniquement ce fichier a donné des traces.

Cordialement,
Jenjen

iPapy · Avril 22, 2021, 6:18

Hello,

Idem chez moi ce matin, redémarrage du demon et ça refonctionne

Plugin en dernière version beta
Jeedom 4.1.22 sous buster sur Rpi3b+

Sigalou si tu veux des log dit moi lesquels

Jenjen · Avril 22, 2021, 6:24

Il faudrait voir si on plante tous en même temps.

Une fois j’avais vu une trace qui informait d’une mise à jour de l’echo, et il me semble que juste après ça avait planté.
Ça coïnciderait avec le fait que ce soit « aléatoire ». Je pense que les maj sont déployés sur tous les devices par Amazon en même temps.

tomdom · Avril 22, 2021, 7:12

Bonjour,

Un scénario programmé avec un bloc code qui contient :

alexaapi::deamon_start();
$scenario->setLog("AlexaApi restarting");

sigalou · Avril 22, 2021, 8:45

tomdom:

Un scénario programmé avec un bloc code qui contient :

ericl780:

Je voudrais redémarrer le démon tous les jours à minuit, avez vous un script pour faire cela ?
alexaapi::deamon_start();
$scenario->setLog("AlexaApi restarting");

c’est pas une solution mais une rustine, je déconseille les rustines.

tomdom · Avril 22, 2021, 8:47

Tout à fait À désactiver une fois que le problème est résolu

sigalou · Avril 22, 2021, 8:48

Bonjour aux derniers posteurs,

Je voudrais me penchez sur vos soucis, mais je n’ai finalement aucun élément. a part me dire que ça fonctionne plus et que vous avez relancé le démon et que tout refonctionne, je n’ai pas de quoi travailler.

si vous voulez que j’identifie un souci, essayez de trouver des logs qui me donnent une piste.

tomdom · Avril 22, 2021, 9:12

En ce qui me concerne, c’est Parler à Alexa qui ne fonctionne plus (Dernier dialogue par exemple continu de se mettre à jour) .
Je vais arrêter la relance quotidienne et attendre que ça se reproduise pour récupérer des logs

Jenjen · Avril 23, 2021, 11:54

Bonjour,

Comme dit plus haut ma seule piste est la mise à jour de mon echo 3.
Lorsque l’echo se met a jour, « l’Activity » change et c’est pour ça que l’on a le problème. En redémarrant le démon on récupère le nouvel Id.

Ca expliquerait le côté aléatoire, et le fait que l’on soit plusieurs a avoir le problème « en même temps ».
De plus le problème arrive le matin, donc est-ce que amazon pousse la mise à jour durant la nuit?

J’ai essayé de voir la liste des releases et quand elles ont été poussé mais j’ai rien trouvé sur le net.
Voila le numéro de release actuelle : 4799221636 (https://www.amazon.com/gp/help/customer/display.html?nodeId=GMB5FVUB6REAVTXY)
Si lors du prochain problème le numéro a changé c’est une bonne piste s’il n’a pas changé ce n’est pas cela.

Je te tiens au jus dès que j’ai du nouveau.

Cordialement,
Jean-Alex

Ultraboss · Avril 23, 2021, 7:38

Bonsoir,
Suite à la mise à jour bêta faite ce matin, la commande speak refonctionne ! Hier soir j’avais tenté une relance du démon mais sans succès. Ce même type d’erreur « no body » m’arrive de temps en temps également, mais sans jamais savoir ce qui fini par corriger le problème…
Par contre lors de la relance du démon après maj ou non, les logs sont parfois en heure am/pm !

Edit: non en fait ce matin j’ai de nouveau cette erreur…

TeXmeX · Avril 24, 2021, 10:37

Pareil sur la version beta

Ultraboss · Avril 24, 2021, 11:41

Mis à jour la béta de ce matin 11h43, et ça refonctionne !

sigalou · Avril 24, 2021, 2:53

Pensez à lancer les dépendances un petit coup pour avoir les dernières bibliothèques.

Ultraboss · Avril 24, 2021, 4:47

Merci du rappel, je l’avais fait quand tu avais annoncé le passage sur nodejs 14.
Par contre depuis la dernière màj avec le nouveau slider (que j’ai remis sur l’ancien modèle) mes 2 équipements ne s’affiche plus sur la version mobile

sigalou · Avril 24, 2021, 7:29

ha oups, j’avais oublié les mobiles !!! Bien vu, je m’y mets

Ultraboss · Avril 28, 2021, 6:33

Bonjour @sigalou,
j’ai de nouveau eu des erreurs de « no body Commands » hier soir alors que rien n’avait été relancé dans la journée :

[27/04/2021, 19:40:08][ERROR] : Alexa-API: Error: no body Commands: [{"command":"volume","value":"25"},{"command":"speak","value":"Attention au moins un véluxe est resté ouvert !"},{"command":"volume","value":"25"}] Query: {"text":"Attention au moins un véluxe est resté ouvert !","volume":"25","lastvolume":"25","device":"f09cc45c8b57425bbea405eebde812bf","value":"Attention au moins un véluxe est resté ouvert !"}
[27/04/2021, 19:40:13][ERROR] : Alexa-API: Error: no body Commands: [{"command":"volume","value":"30"},{"command":"announcement","value":"Attention au moins un véluxe est resté ouvert !"},{"command":"volume","value":"10"}] Query: {"text":"Attention au moins un véluxe est resté ouvert !","volume":"30","jingle":"1","lastvolume":"10","device":"G0911M0793850UT1","value":"Attention au moins un véluxe est resté ouvert !"}
[27/04/2021, 19:40:16][ERROR] : Alexa-API: Error: no body Command: volume Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"25","lastvolume":"25","device":"f09cc45c8b57425bbea405eebde812bf","value":"25"}
[27/04/2021, 19:40:16][] : {API}: Alexa.DeviceControls.Volume: no body
[27/04/2021, 19:40:16][ERROR] : Alexa-API: Error: no body Commands: [{"command":"volume","value":"25"},{"command":"speak","value":"Attention au moins un véluxe est resté ouvert !"},{"command":"volume","value":"25"}] Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"25","lastvolume":"25","device":"f09cc45c8b57425bbea405eebde812bf","value":"Attention au moins un véluxe est resté ouvert !"}
[27/04/2021, 19:40:16][ERROR] : Alexa-API: Error: no body Command: volume Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"25","lastvolume":"25","device":"f09cc45c8b57425bbea405eebde812bf","value":"25"}
[27/04/2021, 19:40:16][] : {API}: Alexa.DeviceControls.Volume: no body
[27/04/2021, 19:40:21][ERROR] : Alexa-API: Error: no body Command: volume Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"30","jingle":"1","lastvolume":"10","device":"G0911M0793850UT1","value":"30"}
[27/04/2021, 19:40:21][] : {API}: Alexa.DeviceControls.Volume: no body
[27/04/2021, 19:40:21][] : {API}    ╔═══════[Lancement /Announcement avec paramètres -> device:G0911M0793850UT1/text:Attention au moins un véluxe est resté ouvert !/volume:30/lastvolume:10
[27/04/2021, 19:40:21][ERROR] : Alexa-API: Error: no body Commands: [{"command":"volume","value":"30"},{"command":"announcement","value":"Attention au moins un véluxe est resté ouvert !"},{"command":"volume","value":"10"}] Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"30","jingle":"1","lastvolume":"10","device":"G0911M0793850UT1","value":"Attention au moins un véluxe est resté ouvert !"}
[27/04/2021, 19:40:21][ERROR] : Alexa-API: Error: no body Command: volume Query: {"replay":"1","text":"Attention au moins un véluxe est resté ouvert !","volume":"30","jingle":"1","lastvolume":"10","device":"G0911M0793850UT1","value":"30"}
[27/04/2021, 19:40:21][] : {API}: Alexa.DeviceControls.Volume: no body

autant sur ma freebox delta que mon echo.
Par contre j’ai remarqué des erreurs dans la log httpd aussi :

[Tue Apr 27 19:40:08.302265 2021] [php7:notice] [pid 29276] [client 192.168.0.101:43142] PHP Notice:  Undefined index: deviceSerialNumber in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 71
[Tue Apr 27 19:40:08.303890 2021] [php7:notice] [pid 29276] [client 192.168.0.101:43142] PHP Notice:  Undefined index: deviceSerialNumber in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 74
[Tue Apr 27 19:40:13.196946 2021] [php7:notice] [pid 3738] [client 192.168.0.101:43180] PHP Notice:  Undefined index: deviceSerialNumber in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 71
[Tue Apr 27 19:40:13.197182 2021] [php7:notice] [pid 3738] [client 192.168.0.101:43180] PHP Notice:  Undefined index: deviceSerialNumber in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 74
[Tue Apr 27 19:40:16.429482 2021] [php7:warn] [pid 29276] [client 192.168.0.101:43142] PHP Warning:  file_get_contents(http://192.168.0.101:3456/volume?replay=1&amp;text=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21&amp;volume=25&amp;lastvolume=25&amp;device=f09cc45c8b57425bbea405eebde812bf&amp;value=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21): failed to open stream: HTTP request failed! HTTP/1.1 500 Internal Server Error
in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 98
[Tue Apr 27 19:40:16.516989 2021] [php7:warn] [pid 29276] [client 192.168.0.101:43142] PHP Warning:  file_get_contents(http://192.168.0.101:3456/volume?replay=1&amp;text=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21&amp;volume=25&amp;lastvolume=25&amp;device=f09cc45c8b57425bbea405eebde812bf&amp;value=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21): failed to open stream: HTTP request failed! HTTP/1.1 500 Internal Server Error
in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 98
[Tue Apr 27 19:40:21.290650 2021] [php7:warn] [pid 3738] [client 192.168.0.101:43180] PHP Warning:  file_get_contents(http://192.168.0.101:3456/volume?replay=1&amp;text=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21&amp;volume=30&amp;jingle=1&amp;lastvolume=10&amp;device=G0911M0793850UT1&amp;value=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21): failed to open stream: HTTP request failed! HTTP/1.1 500 Internal Server Error
in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 98
[Tue Apr 27 19:40:21.401447 2021] [php7:warn] [pid 3738] [client 192.168.0.101:43180] PHP Warning:  file_get_contents(http://192.168.0.101:3456/volume?replay=1&amp;text=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21&amp;volume=30&amp;jingle=1&amp;lastvolume=10&amp;device=G0911M0793850UT1&amp;value=Attention+au+moins+un+v%C3%A9luxe+est+rest%C3%A9+ouvert+%21): failed to open stream: HTTP request failed! HTTP/1.1 500 Internal Server Error
in /var/www/html/plugins/alexaapi/core/php/jeeAlexaapi.php on line 98

Si ça peut t’aider

tomdom · Avril 28, 2021, 10:10

Bonjour,

Ça y est le problème vient de se reproduire.
Lancement d’une commande Parler à Alexa ko :

[2021-4-28 12:03:56][INFO] : {API}    ╔═══════[Lancement /textCommand avec paramètres -> device: G090XG1001571CSP & text: Bluetooth══════════════════════════════════
[2021-4-28 12:03:57][ERROR] : Alexa-API: Error: no body Command: textCommand Query: {"replay":"1","text":"Bluetooth","device":"G090XG1001571CSP"}
[2021-4-28 12:03:57][] : {API}: Alexa.DeviceControls.textCommand: no body
[2021-4-28 12:04:02][INFO] : {API}    ╔═══════[Lancement /textCommand avec paramètres -> device: G090XG1001571CSP & text: Spotify playlist Daily mix trois══════════════════════════════════
[2021-4-28 12:04:02][ERROR] : Alexa-API: Error: no body Command: textCommand Query: {"replay":"1","text":"Spotify playlist Daily mix trois","device":"G090XG1001571CSP"}
[2021-4-28 12:04:02][] : {API}: Alexa.DeviceControls.textCommand: no body

Après redémarrage d’AlexaApi

[4/28/2021, 12:05:57 PM][INFO] : {API}    ╔═══════[Lancement /textCommand avec paramètres -> device: G090XG1001571CSP & text: Bluetooth══════════════════════════════════
[4/28/2021, 12:06:02 PM][INFO] : {API}    ╔═══════[Lancement /textCommand avec paramètres -> device: G090XG1001571CSP & text: Spotify playlist Daily mix trois══════════════════════════════════
[4/28/2021, 12:06:05 PM][INFO] : {API}    ╔═══════[Lancement  /playerInfo sur G090XG1001571CSP
[4/28/2021, 12:06:05 PM][INFO] : {API}    ╔═══════[Lancement  /playerInfo sur G090XG1001571CSP
[4/28/2021, 12:06:06 PM][INFO] : {API}    ╔═══════[Lancement  /playerinfo sur G090XG1001571CSP
[4/28/2021, 12:06:06 PM][INFO] : {API}    ╔═══════[Lancement  /playerinfo sur G090XG1001571CSP
[4/28/2021, 12:06:07 PM][INFO] : {API}    ╔═══════[Lancement  /media sur G090XG1001571CSP
[4/28/2021, 12:06:07 PM][INFO] : {API}    ╔═══════[Lancement  /media sur G090XG1001571CSP

Si ça peut aider

Jenjen · Avril 28, 2021, 11:11

Bonjour,

De mon côté c’est pareil, alexa ne parle plus.

Version de l’echo : 5268811396
Il y a eu une mise à jour par rapport à mon précédent message. Par contre je ne sais pas si c’est aujourd’hui ou un autre jour, mais je pense que c’est ce matin que mon echo s’est mis à jour car le site de amazon n’est pas encore à jour.
La dernière commande qui a marché est 7h15

J’ai ces traces la sans que je ne fasse rien au niveau du démon (la commande vocal 5 minutes après ces traces n’a pas fonctionné) :

[2021-04-28 12:05:05][INFO] :  Arrêt du service alexaapi
[2021-04-28 12:05:09][INFO] :  Lancement du démon alexaapi
[2021-04-28 12:05:09][DEBUG] : Lancement démon alexaapi : nice -n 19 nodejs /var/www/html/plugins/alexaapi/resources/alexaapi.js http://192.168.0.20 amazon.fr alexa.amazon.fr XXX 100
[2021-04-28 12:05:09][INFO] :  Démon alexaapi lancé
....
[2021-04-28 12:22:14][INFO] : Envoi de http://192.168.0.20:3456/speak?text=Le+portail+se+ferme.&volume=20&lastvolume=20&device=G2A14Q04011503CU
[2021-04-28 12:22:14][DEBUG] : Réception données sur jeeAlexaapi [commandesEnErreur]

Est-ce que c’est du à la mise à jour ou c’est en automatique?

J’ai relancé le démon et tout refonctionne. Au niveau des traces à part celle du dessus rien qui puisse nous indiquer une mise à jour ou un autre problème. J’ai sauvegardé les logs DEBUG au cas ou.

JenJen

sigalou · Avril 28, 2021, 5:00

Bonsoir,

J’ai l’impression qu’Amazon fait pas mal de mises à jour de ces appareils tous les jours.
Par exemple, il y a quelques jours, je n’avais pas le « bip » au changement de volume et il est revenu 4h après.
Visiblement, quand l’appareil se met à jour, le daemon doit être relancé.
Je continue les essais…

sigalou · Avril 28, 2021, 6:56

Ce soir, je publie une beta, rien de spécial, juste des logs de plus pour tester quelques trucs.
C’est pénible, j’ai eu le blocage tout à l’heure, j’ai bien constaté comme vous les messages avec le replay=1 cela veut dire que le plugin détecte un souci et rejoue la commande.
C’est assez perturbant car si on interroge le serveur, il répond. Genre on lui demande la prochaine alarme, l’info remonte bien et on a une réponse mais si on lui demande de changer un volume ou si on utilise « parler à alexa », on a une erreur.
Mon souci c’est qu’une relance du daemon solutionne le souci, donc je ne peux pas faire trop de tests, dès que je relance mon daémon tout refonctionne bien (trop bien). Et je n’ai pas trouvé ce qui « bloquait » le lien avec le serveur… donc je cherche à le reproduire.
Si vous pouvez mettre a jour la beta, augmenter un peu le nb de lignes de log (500 c’est juste) et arriver à identifier une commande de volume par exemple qui passe puis une commande de volume qui ne passe pas. Il me faudrait ce qui s’est passé entre les deux, que je cherche le moment du « blocage ».
J’espère avoir été clair, je cherche… merci pour votre aide.