Problem Sayit - doppelte Ansagen und sich wiederholende.

firstfreak

Ausgabe ist Browser, in meinem Fall ein Firefox-Browser / Google Chrome Browser auf Windows 10

Bluefox

Ausgabe ist Browser, in meinem Fall ein Firefox-Browser / Google Chrome Browser auf Windows 10 `
Man kann im Browser die Lautstärke nicht ändern. Oder willst du, dass jede Seite dir was mit 1000% Lautstärke abspielt? :D

Fitti

Hänge mich mal ran, da ich das gleiche/ähnliche Problem habe.

Seit wann: Seit ca. 14 Tagen - kann auch mehr sein. Fehler ist plötzlich erschien. Vorher seit Monaten problemlos gegangen. Vermutlich hatte ich ein Update und/oder reboot auf meiner Linux Maschine.

Ausgabe: Tablet per FTP MediaPlayer24, Ilona (amazon).

Was passiert: Unterschiedlich oft wird eine Ansage geschickt. Das kann ich auch schön im FTP auf dem Tablet sehen.

Merkwürdig: Wenn ich immer wieder das folgende Skript starte, wird es mal 1x gesendet (okay) oder 2-4x. Immer unterschiedlich.

=> Aber fast immer mindestens 2x.

Testscript total simple:

var idSayIt = "sayit.0.tts.text";
ansage = "Guten Abend!";
  setState (idSayIt, ansage);

Log: (hier mit 2x gesendet - aber nur einmal gestartet)

! ````
sayit-0 2016-10-26 18:17:47.435 info Copied file "/opt/iobroker/node_modules/iobroker.sayit/say.mp3" to "/opt/iobroker/cache/a1986f82ec42937375db38dbad800e31.mp3"
sayit-0 2016-10-26 18:17:47.427 debug Duration "Guten Abend!": 0
sayit-0 2016-10-26 18:17:47.285 info Copied file "/opt/iobroker/node_modules/iobroker.sayit/say.mp3" to "/opt/iobroker/cache/a1986f82ec42937375db38dbad800e31.mp3"
sayit-0 2016-10-26 18:17:46.889 info saying: Guten Abend!
sayit-0 2016-10-26 18:17:46.861 info saying: Guten Abend!
host-odibroker 2016-10-26 18:17:44.198 warn instance system.adapter.sayit.0 already running with pid 30074
sayit-0 2016-10-26 18:17:43.886 debug Duration "/opt/iobroker/node_modules/iobroker.sayit/gong.mp3": 3
sayit-0 2016-10-26 18:17:43.864 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
host- 2016-10-26 18:17:43.855 debug redis pmessage io.sayit.0.* io.sayit.0.tts.text {"val":"Guten Abend!","ack":false,"ts":147749
sayit-0 2016-10-26 18:17:43.854 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
javascript-1 2016-10-26 18:17:43.849 info script.js.Test.Saytest: registered 0 subscriptions and 0 schedules
javascript-1 2016-10-26 18:17:43.841 info Start javascript script.js.Test.Saytest
javascript-0 2016-10-26 18:17:43.833 info Stop script script.js.Test.Saytest


Frage mich, was es mit dem __instance system.adapter.sayit.0 already running__ auf sich hat.

Fitti

Homoran

Dann hänge ich mich jetzt auch rein.

Habe seit reinigen Tagen ein neues tablet auf dem ich den home24 media mediaplayer noch nicht installiert habe und daher auch keine Meldungen bekomme.

Ich experimentiere in der Richtung noch. Unter anderem auch mit dem Tonnengong. Der brüllt laut durchs Haus.

Zeitgleich höre ich aber gerade aus der Schublade in die ich das alte tablet verbannt habe die Meldung mindestens zweimal.

Gruß

Rainer

rascal

Ich habe seit kurzem auch das Problem das manche Ansage mehrfach gesagt werden, nicht nur auf Android, sondern auch über sayit Adapter im Multihost Modus

Bluefox

Ab und zu wird Adapter sayit nicht richtig beendet und bleibt im Speicher hängen. Dabei bleibt er voll funktionsfähig.

Bitte anschauen "ps -A | grep io"

Wieviele sayit laufen.

rascal

im Moment nur eine, ich melde mich wieder wenn die doppelte Ansage wieder auftritt, danke

ruhr70

~~@Bluefox:~~

Ab und zu wird Adapter sayit nicht richtig beendet und bleibt im Speicher hängen. Dabei bleibt er voll funktionsfähig.

Bitte anschauen "ps -A | grep io"

Wieviele sayit laufen. `

Bei mir werden die Ansagen auch mehrfach abgespielt.

Nur eine Instanz sayit.0 aktiv.

Im Log findet man den sayit Aufruf dann auch mehrmals.

Nicht nachvollziehbar. Mal einmal, mahl mehrmals, mal lauter, mal leiser.

Bluefox

Ihr seid viele Entwickler, wenn jemand zu euch mit der Aussage kommt:
> Nicht nachvollziehbar. Mal einmal, mahl mehrmals, mal lauter, mal leiser.
Was wird ihr antworten?

Ich vermute doch zwei Instanzen, nun kannst du die vielleicht nicht sehen.

Es kann aber sein, dass Skript 2 mal triggert.

Hast du System oder Kommando als Ausgabe?

Homoran

~~@Bluefox:~~

Ihr seid viele Entwickler, wenn jemand zu euch mit der Aussage kommt:

Nicht nachvollziehbar. Mal einmal, mahl mehrmals, mal lauter, mal leiser.

Was wird ihr antworten? `
Alles Mist! :mrgreen:

Jetzt mal im Ernst; wenn das Phänomen so schwer zu packen ist, und dann auch nicht reproduzierbar ist es natürlich auch nicht "mal eben" zu fixen.

Der Aufruf von Bluefox ist natürlich sehr wichtig: Bitte versuchen systematisch Zusammenhänge zu erkennen. Alles andere ist nicht hilfreich. Die Aussage von ruhr70 war dann wohl auch eher gemeint als: Ich finde da kein System hinter.

Aber sollte nicht diese Information:
~~@ruhr70:~~

Im Log findet man den sayit Aufruf dann auch mehrmals. `
zumindest für diesen Fall zeigen, dass die Ursache für die Verdopplung auf der Seite des Host zu suchen ist und nicht bei dem Client?

Gruß

Rainer

ruhr70

~~@Bluefox:~~

Ihr seid viele Entwickler, wenn jemand zu euch mit der Aussage kommt:
> Nicht nachvollziehbar. Mal einmal, mahl mehrmals, mal lauter, mal leiser.
Was wird ihr antworten?

Ich vermute doch zwei Instanzen, nun kannst du die vielleicht nicht sehen.

Es kann aber sein, dass Skript 2 mal triggert.

Hast du System oder Kommando als Ausgabe? `

Der Hinweis war nicht zur Fehlereingrenzung gedacht, sondern eher als Feedback, dass ich das Problem bestätigen kann. Statistik ;-)

In der Tat habe ich mich noch nicht mit der weiteren Fehlereingrenzung beschäftigt. Bevor ich aber irgendwann ganz auf die Funktion verzichte, werde ich da bestimmt mehr Energie einsetzen (Debug Level, usw.). Bisher war der "Schmerz" noch nicht groß genug.

Die spärlichen Infos, die ich derzeit liefern kann:

Als Ausgabe habe ich Sonos gewählt.
Es gibt auch noch zwei weitere SayIt Instanzen .1 und .2 mit anderen Sonos Lautsprechern, die aber nicht angesprochen werden.
mit "ps -A | grep io" sehe ich keine weiteren Instanzen. Ich überwache doppelte Instanzen zusätzlich per Skript, da ich schon anderweitig mal einen Fehler hatte. Auch dort ist mir bisher nichts aufgefallen.
Die Ansage, die derzeit Probleme macht, läuft mit unveränderten Skript schon lange. Sie hat eine ganze Zeit recht gut funktioniert, bis darauf, dass die Lautstärke sporadisch sehr laut wurde.
Seit einiger Zeit gibt es die Mehrfachansagen (ohne Skriptänderung oder Änderung beteiligter Datenpunkte). Zwischendurch gab es einige Adapter -Updates (Sonos, SayIt, …). Seit wann genau das Problem auftritt, kann ich nicht sagen. Andere Ansagen habe ich deswegen mittlerweile deaktiviert.

Ich weiss, dass auch das zur Fehlereingrenzung nicht besonders beiträgt, werde es bei Gelegenheit mal näher analysieren und durchtesten.

Grummel... vor dem Abschicken habe ich meine 6:45 Ansage einmal manuell gestartet.

Diese wurde nun dreimal abgespielt. Jetzt war der sayit.0 Prozess allerdings doppelt aktiv:

javascript.1	2016-10-27 20:56:00.655	warn	javascript.1 script.js.Daten.iobroker_host_Server_Info.iobroker_Speicherverbrauch: Prozess: io.sayit.0, mehrfach aktiv: 2 mal

Ich schau mal, ob ich noch was testen kann, ohne den Nachwuchs zu wecken.

ruhr70

Anbei ein Beispiel.

Drei Ansagen.

Ansage 1 (Arbeitstag…) und 2 (Wetterlage) wurden jeweils richtig einmal abgespielt.

Die dritte Ansage (die aktuelle Temperatur beträgt …) wurde zweimal abgespielt.

Das war der zweite Versuch. Im ersten Versuch wurde jede Ansage einmal abgespielt.

Log:

! ````
[sonos] 2016-10-27 21:18:44.707 info emitting group-volume
[sonos] 2016-10-27 21:18:39.776 info emitting group-volume
[sonos] 2016-10-27 21:18:39.168 info emitting group-volume
[sonos] 2016-10-27 21:18:38.858 info emitting group-volume
[sonos] 2016-10-27 21:18:33.398 info emitting group-volume
sonos-0 2016-10-27 21:18:32.725 info try to control id sonos.0.root.172_16_130_146.tts with {"val":"50;http://172.16.130.210:8082/state/sayit.0.tts.mp3","ack":false,"ts":1477595912723,"q":0,"from":"system.adapter.sayit.0","lc":147759591
sayit-0 2016-10-27 21:18:32.723 info Set "sonos.0.root.172_16_130_146.tts: 50;http://172.16.130.210:8082/state/sayit.0.tts.mp3
sayit-0 2016-10-27 21:18:32.722 info saying: Die aktuelle Temperatur beträgt 10 Grad Celsius.
sonos-0 2016-10-27 21:18:31.723 info try to control id sonos.0.root.172_16_130_146.tts with {"val":"35;http://172.16.130.210:8082/state/sayit.0.tts.mp3","ack":false,"ts":1477595911721,"q":0,"from":"system.adapter.sayit.0","lc":147759591
sayit-0 2016-10-27 21:18:31.720 info Set "sonos.0.root.172_16_130_146.tts: 35;http://172.16.130.210:8082/state/sayit.0.tts.mp3
sayit-0 2016-10-27 21:18:31.719 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
javascript-0 2016-10-27 21:18:31.717 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: de;50;Die aktuelle Temperatur beträgt 10 Grad Celsius.
javascript-0 2016-10-27 21:18:31.717 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: 50
[sonos] 2016-10-27 21:18:31.056 info emitting group-volume
[sonos] 2016-10-27 21:18:30.682 info emitting group-volume
[sonos] 2016-10-27 21:18:30.252 info emitting group-volume
sonos-0 2016-10-27 21:18:22.723 info try to control id sonos.0.root.172_16_130_146.tts with {"val":"50;http://172.16.130.210:8082/state/sayit.0.tts.mp3","ack":false,"ts":1477595902722,"q":0,"from":"system.adapter.sayit.0","lc":147759589
sayit-0 2016-10-27 21:18:22.722 info Set "sonos.0.root.172_16_130_146.tts: 50;http://172.16.130.210:8082/state/sayit.0.tts.mp3
sayit-0 2016-10-27 21:18:22.721 info saying: Wetterlage Wolkig
javascript-0 2016-10-27 21:18:22.718 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: de;50;Wetterlage Wolkig
javascript-0 2016-10-27 21:18:22.718 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: 50
[sonos] 2016-10-27 21:18:21.125 info emitting group-volume
[sonos] 2016-10-27 21:18:20.489 info emitting group-volume
sonos-0 2016-10-27 21:18:18.724 info try to control id sonos.0.root.172_16_130_146.tts with {"val":"50;http://172.16.130.210:8082/state/sayit.0.tts.mp3","ack":false,"ts":1477595898721,"q":0,"from":"system.adapter.sayit.0","lc":147759589
sayit-0 2016-10-27 21:18:18.721 info Set "sonos.0.root.172_16_130_146.tts: 50;http://172.16.130.210:8082/state/sayit.0.tts.mp3
sayit-0 2016-10-27 21:18:18.721 info saying: Es ist ein Arbeitstag und es ist viertel vor sieben
[sonos] 2016-10-27 21:18:18.273 info emitting group-volume
sonos-0 2016-10-27 21:18:17.722 info try to control id sonos.0.root.172_16_130_146.tts with {"val":"35;http://172.16.130.210:8082/state/sayit.0.tts.mp3","ack":false,"ts":1477595897720,"q":0,"from":"system.adapter.sayit.0","lc":147759589
sayit-0 2016-10-27 21:18:17.720 info Set "sonos.0.root.172_16_130_146.tts: 35;http://172.16.130.210:8082/state/sayit.0.tts.mp3
sayit-0 2016-10-27 21:18:17.719 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
javascript-0 2016-10-27 21:18:17.716 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: registered 0 subscriptions and 1 schedule
javascript-0 2016-10-27 21:18:17.716 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: de;50;Es ist ein Arbeitstag und es ist viertel vor sieben
javascript-0 2016-10-27 21:18:17.716 info script.js.Ereignisse.Zeiten.06-45_Arbeitstag: 50
javascript-0 2016-10-27 21:18:17.713 info Start javascript script.js.Ereignisse.Zeiten.06-45_Arbeitstag

Fitti

Ich habe im Log wiederkehrend stehen, dass der sayit.0 nicht gestartet wird,da er bereits læuft.

Das zu Zeiten wo gar keine Ansage verwendet wird.

Irgendwas startet also ihn immer wieder. Mit debug habe ich keine näheren infos erhalten.

Möchte nochmal auf meine Erkenntnis hinweisen, das nur eine einfache Ansage immer wieder per Hand gestartet ,also Skript gestartet, unterschiedliche Ansageanzahlen bringt.

Fitti.

Homoran

Bei mir ist es systematisch:

sayit-0	2016-10-27 23:04:36.452	info	saying: Der Trockner ist fertig
sayit-0	2016-10-27 23:04:36.447	info	saying: Der Trockner ist fertig
sayit-0	2016-10-27 23:04:33.447	info	saying: /opt/iobroker/node_modules/iobroker.sayit/scifi.mp3
sayit-0	2016-10-27 23:04:33.443	info	saying: /opt/iobroker/node_modules/iobroker.sayit/scifi.mp3

Quelle:

on({id: "hm-rpc.1.NEQ0386317.3.DECISION_VALUE"/*Trockner:3.DECISION_VALUE*/, change: "lt"}, function (obj) {
  var value = obj.state.val;
  var oldValue = obj.oldState.val;
  setState("sayit.0.tts.text", "de;100;" + 'Der Trockner ist fertig');
});

Home24-Mediaplayer ohne ftp.

Habe gerade den Ankündigungston herausgenommen und die Konfiguration gesichert -> nur noch eine Ansage.

Dafür jetzt die Fehlermeldung:

instance system.adapter.sayit.0 terminated with code 7 (Adapter already running)

Erneute Änderung der Konfig wieder mit Ankündigungston brachte keine Änderung.

Nur eine Ansage aber der Fehler bleibt. kommt etwa alle 33 Sekunden.

Gruß

Rainer

Fitti

Seltsam… bei mir hängen wohl irgendwelche Updates...

ps -ef |grep say:

root     25026   535  0 Oct23 ?        00:00:42 node /opt/iobroker/node_modules/iobroker.js-controller/iobroker.js upgrade sayit
root     25035 25026  0 Oct23 ?        00:00:00 /bin/sh -c npm install iobroker.sayit --production --prefix "/opt/iobroker"
root     25046 25045  0 Oct23 ?        00:04:01 io.sayit.0

Hatte a 23.10. aber kein Reboot noch Update. Der say.it.0 war auch von der Oberfläche deaktiviert.

Ein kill auf diese drei Prozesse haben gleich den gesamten iobroker runtergezogen. Ein "iobroker stop" zeigte dann: deamon is not running.

iobroker start lief dann

Bluefox

Auf dem git liegt neue Version 1.2.0, die ich versuche mit 3 verschiedenen Methoden zu killen. Irgendeine muss schon wirken. :lol:

Homoran

Ich weiß nicht ob diese Information jetzt noch hilft.

Nach fittis Posting fiel mir ein, dass ich bei Updates auch mal hängen geblieben in, ich meine insbesondere wenn ich die "alles updaten" Option im admin benutzt habe.

Dann benutze ich das Script Unfall json Widget von ruhr70 mit dem die laufenden Prozesse dargestellt wurden. Darin befanden sich letzte zeit weitere Zeilen mit mir unerklärlichem Inhalt.

In den letzten Tagen habe ich leider bei beiden Installationen einen reboot gemacht NDR diese Zusatzzeilen sind wieder verschwunden.

Ich hatte da leider keinen Zusammenhang gesehen :oops:

Aber ich denke irgendwo habe ich davon noch einen Screenshot. Leider nicht hier auf dem tablet.

Bin auch gleich weg auf Schwiegermutters 80. Und Schwiegersohns 33. Geburtstag(e)

Gruß

Rainer

Homoran

Habe noch schnell auf dem anderen Tablet nachgesehen

Irgendwann war da auch eine 4 drin und im Log erschien ein warn dass der Prozess 4 doppelt Sri.

Vielleicht hilft es ja doch noch.

Gruß

Rainer

ruhr70

~~@Bluefox:~~

Auf dem git liegt neue Version 1.2.0, die ich versuche mit 3 verschiedenen Methoden zu killen. Irgendeine muss schon wirken. :lol: `

Installiert. Ich beobachte und berichte ;-)

Bluefox

~~@Fitti:~~

Hänge mich mal ran, da ich das gleiche/ähnliche Problem habe.

Seit wann: Seit ca. 14 Tagen - kann auch mehr sein. Fehler ist plötzlich erschien. Vorher seit Monaten problemlos gegangen. Vermutlich hatte ich ein Update und/oder reboot auf meiner Linux Maschine.

Ausgabe: Tablet per FTP MediaPlayer24, Ilona (amazon).

Was passiert: Unterschiedlich oft wird eine Ansage geschickt. Das kann ich auch schön im FTP auf dem Tablet sehen.

Merkwürdig: Wenn ich immer wieder das folgende Skript starte, wird es mal 1x gesendet (okay) oder 2-4x. Immer unterschiedlich.

=> Aber fast immer mindestens 2x.

Testscript total simple:
var idSayIt = "sayit.0.tts.text";
ansage = "Guten Abend!";
 setState (idSayIt, ansage);
Log: (hier mit 2x gesendet - aber nur einmal gestartet)

! ````
sayit-0 2016-10-26 18:17:47.435 info Copied file "/opt/iobroker/node_modules/iobroker.sayit/say.mp3" to "/opt/iobroker/cache/a1986f82ec42937375db38dbad800e31.mp3"
sayit-0 2016-10-26 18:17:47.427 debug Duration "Guten Abend!": 0
sayit-0 2016-10-26 18:17:47.285 info Copied file "/opt/iobroker/node_modules/iobroker.sayit/say.mp3" to "/opt/iobroker/cache/a1986f82ec42937375db38dbad800e31.mp3"
sayit-0 2016-10-26 18:17:46.889 info saying: Guten Abend!
sayit-0 2016-10-26 18:17:46.861 info saying: Guten Abend!
host-odibroker 2016-10-26 18:17:44.198 warn instance system.adapter.sayit.0 already running with pid 30074
sayit-0 2016-10-26 18:17:43.886 debug Duration "/opt/iobroker/node_modules/iobroker.sayit/gong.mp3": 3
sayit-0 2016-10-26 18:17:43.864 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
host- 2016-10-26 18:17:43.855 debug redis pmessage io.sayit.0.* io.sayit.0.tts.text {"val":"Guten Abend!","ack":false,"ts":147749
sayit-0 2016-10-26 18:17:43.854 info saying: /opt/iobroker/node_modules/iobroker.sayit/gong.mp3
javascript-1 2016-10-26 18:17:43.849 info script.js.Test.Saytest: registered 0 subscriptions and 0 schedules
javascript-1 2016-10-26 18:17:43.841 info Start javascript script.js.Test.Saytest
javascript-0 2016-10-26 18:17:43.833 info Stop script script.js.Test.Saytest
Frage mich, was es mit dem __instance system.adapter.sayit.0 already running__ auf sich hat.

Fitti `

Dein Fall ist klassischen 2 Instanzen-Fall. Also auch neue Version ausprobieren. Aber natürlich es muss gewährleistet werden, dass vorherige Version tatsächlich aus ist.

Community Forum

NEWS

Problem Sayit - doppelte Ansagen und sich wiederholende.

Support us

365

32.6k

82.0k

1.3m