AMADNG Sprachsteuerung - Befehl wird doppelt ausgeführt

[Gisbert]

Hallo Leon,

seit ca. 2 bis 3 Wochen stelle ich ein merkwürdiges Verhalten fest, wenn ich eine Spracheingabe in Google
mit AMADNG tätige.

Du hattest mir in deinem Thread AMADNG gesagt, das ich unter der Rubrik Sprachsteuerung einen neuen Thread eröffnen soll.

Zuerst habe ich bemerkt, dass manchmal die gesprochene Rückantwort zweimal ausgegeben wird. Ich hab mich gewundert, dem aber nicht allzuviel Bedeutung zugemessen.

Jetzt stelle ich fest, dass der Befehl vermutlich kurz hintereinander zweimal ausgeführt wird.
Es geht um eine Rollladenschaltung, bei dem im Sketch auf einem Sonoff Dual zuerst beide Relais ausgeschaltet werden, dann nach einer Wartezeit von 300 Millisekunden, das dem Befehl entsprechende Relais geschaltet wird.
Was zu hören ist, ist ein kurzes Anlaufen des Motors, dann Anhalten, dann Weiterfahren.

Mir ist klar, dass ohne weitere Angaben eine Diagnose​ nicht möglich ist. Bevor ich dich aber mit allen möglichen Files zumülle, wollte ich dich fragen, was ich sinnvollerweise posten kann.

Viele Grüße Gisbert

[CoolTux]

Hallo Gisbert,

Das der Flow zweimal ausgeführt wird ist recht eigenartig. Denn im normal Fall sollte er nur einmal triggern.
Das aber zweimal die Antwort ausgegeben wir spricht dafür.

Gehe mal in den Flow rein und gehe oben auf die 3 Punkte in den Menüpunkt Optionen dort stellst du auf "Warte bis die aktuell ausgeführte Instanz..."

Das sollte Dein Problem hoffentlich beheben.



Grüße
Leon

[Gisbert]

Hallo Leon,

hab deinen Vorschlag probiert, hat aber noch keinen Erfolg gehabt. Ich hab nichts neu gestartet, wäre da was nötig?
Kann es mit dem Wlan-Router zu tun haben, der scheint Aussetzer zu haben, nur so eine Idee?

Wie kann ich versuchen den Fehler einzugrenzen?

Viele Grüße Gisbert

[CoolTux]

Neu starten musst du da nichts. Sobald die Option gesetzt ist sollte er erst den ein zweitesmal starten wenn er den ersten Durchlauf fertig hat. Die Frage ist aber wieso er den Flow zweimal startet. Das macht keinen Sinn. Hast Du als Trigger zwei oder mehr drin stehen oder nur ein Trigger mit AutoVoice auf die korrekte RegEx?

[Gisbert]

Hallo Leon,

leider kann ich deine Fragen nicht korrekt beantworten. Du hattest mir geholfen die Sache in die Gänge zu bringen, noch unter AMAD2. Ich füge den Flow an. Dieses doppelte Schalten betrifft mein altes und neues Handy.

Ein 2. Problem betrifft mein neues Handy LG G6.
Wenn ich "Ok Google" sage, dann wird der Sprachbefehl in Google aufgenommen, Google startet eine Suche, aber ansonsten passiert nichts. Wenn ich das Mikrofonsymbol anklicke, dann wird der Sprachbefehl (doppelt) ausgeführt. Auf dem neuen Handy erscheinen die Popups der Eingabe und des abgesetzten Befehle nicht mehr.

Viele Grüße Gisbert

[CoolTux]

Hallo Gisbert,

Ich kann im Flow erstmal nichts feststellen. Stell mal bitte unter Optionen auf Aussetzen wenn eine Instanz schon läuft.


Grüße

[Gisbert]

Hallo Leon,

ich hab den Flow "Rollladen per Sprache steuern" und den Flow "Send data to AMADCommBridge" jetzt auf die Option "Aussetzen, wenn eine Instanz schon läuft" gesetzt.
Es ist das gleiche Verhalten zu sehen, dass unmittelbar hintereinander der Befehl doppelt ausgeführt wird.
Mal geschieht es in der gleichen Sekunde, mal wird die nächste Sekunde angezeigt; gefühlt dauert es ca. 200-300 ms.
Verbose 5 des Devices zeigt:

Code Auswählen Erweitern

2017.08.29 18:22:07 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:22:07 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:23:02 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:23:03 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:23:41 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:23:41 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:28:32 4: dummy set myRollladenSchlafzimmerGisbert up
2017.08.29 18:28:33 4: dummy set myRollladenSchlafzimmerGisbert up

Ein Versuch:
Wenn ich in AutoVoice einen entsprechenden Testcommand ausführe, dann wird der Befehl auch doppelt ausgeführt; es liegt demnach anscheinend nicht am Google Assitant.

Viele Grüße Gisbert

[CoolTux]

Bitte nicht am "Send data to AMADCommBridge"  Flow manipulieren. Sonst gehen Dir Daten/Zustände verloren.

Hast du noch andere Sprachflows? Ist es da ähnlich? Bin bisschen überfragt muss ich gestehen.

[tiroso]

Mit welchen Geräten ist denn das Rollo verbunden?

In dem Gerät steht weiter unten Probably associated with.

Nicht das du so ein Pech hast und vllt noch ein Notify reagiert. Oder irgendein anderes Modul? TEERKO?

[Gisbert]

@Leon, ich hab die Optionen wieder auf "Warte bis die ursprünglichen ..." gestellt.

@tiroso, der Gedanke ist mir auch schon gekommen. Ich martere schon mein Gehirn, kann aber nichts finden, zumal ich in letzter Zeit an dieser Front nichts unternommen habe. Ich werde aber systematisch meine config durchgehen und diesen Punkt klären.

Viele Grüße und besten Dank bis hierhin
Gisbert​

[tiroso]

Oder kann es sein...auch ne Frage anLeon..
Das eine 'unsichtbare leiche' aus der Kombination Old Stable AMAD, Beta AMADNG und New Release AMADNG übrig geblieben ist der nun 'lauscht' und immernoch reagiert?

[CoolTux]

Nicht das ich wüsste. Der Flow auf den hier Bezug genommen wird hat ja nichts mit den AMAD Flows zu tun. Es ist ein eigenständiger User Flow. Getriggert durch AutoVoice.
Das mehrfach auslösen ist so eigentlich nicht möglich. Zu mindest mir nicht bekannt. Man kann aber mal nach dem Trigger eine Condition Debug setzen und damit schauen ob der Flow wirklich mehrmals triggert.

[tiroso]

Ne ich meinte nicht die flows..vllt gab es durch die Unstellung eine Bridge die iwie übrig geblieben ist.

Was sagt denn die Bridge wenn du die aif verbose 5 setzt. Gibt die solche Infos was passiert?

[Gisbert]

Hallo Leon,
zeitlich würde das Problem der doppelten Ausführung zum Umstieg von AMAD2 auf AMADNG in etwa hinkommen, wobei mir aber das Wissen und die Phantasie fehlt, ob das überhaupt sein kann.
Für die allermeisten Anwendungen dürfte eine doppelte Ausführung auch egal sein. In meinem Fall besteht der Fahrbefehl des Rollladens aus 2 Teilen zum Schutz des Rollladenmotors: a) ein Stoppbefehl, b) ein Fahrbefehl.

Hallo tiroso,
ich setze die Bridge auf verbose 5 und melde mich dann.

Viele​ Grüße​ Gisbert​

[CoolTux]

Interessant wäre raus zu finden was wie doppelt ausgeführt wird. Wird tatsächlich der Flow doppelt ausgeführt oder kommt das doppelte erst in FHEM zu stande.

[Gisbert]

Das kommt bei einem Befehl mit verbose 5 bei der Bridge raus, reichlich für einen Befehl:

Code Auswählen Erweitern

2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_58995
2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_58996
2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_58997
2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_58998
2017.08.30 07:52:39 4: AMADCommBridge (myAMADBridge_192.168.178.52_58998) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","deviceState": "online"}}
2017.08.30 07:52:39 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","deviceState": "online"}}
2017.08.30 07:52:39 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:39 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:39 4: AMADCommBridge (myAMADBridge) - call Dispatcher
2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_58999
2017.08.30 07:52:39 4: Connection accepted from myAMADBridge_192.168.178.52_59000
2017.08.30 07:52:39 4: AMADCommBridge (myAMADBridge_192.168.178.52_59000) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","flow_informations": "aktiv"}}
2017.08.30 07:52:39 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","flow_informations": "aktiv"}}
2017.08.30 07:52:39 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:39 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:39 4: AMADCommBridge (myAMADBridge) - call Dispatcher
2017.08.30 07:52:44 4: Connection accepted from myAMADBridge_192.168.178.52_59005
2017.08.30 07:52:44 4: Connection accepted from myAMADBridge_192.168.178.52_59007
2017.08.30 07:52:44 4: AMADCommBridge (myAMADBridge_192.168.178.52_59007) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","deviceState": "online","flow_SetCommands": "aktiv"}}
2017.08.30 07:52:44 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","deviceState": "online","flow_SetCommands": "aktiv"}}
2017.08.30 07:52:44 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:44 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:44 4: AMADCommBridge (myAMADBridge) - call Dispatcher
2017.08.30 07:52:44 4: Connection accepted from myAMADBridge_192.168.178.52_59008
2017.08.30 07:52:45 4: Connection accepted from myAMADBridge_192.168.178.52_59009
2017.08.30 07:52:45 4: AMADCommBridge (myAMADBridge_192.168.178.52_59009) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","checkActiveTask": "0","deviceState": "online","flow_SetCommands": "aktiv"}}
2017.08.30 07:52:45 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","checkActiveTask": "0","deviceState": "online","flow_SetCommands": "aktiv"}}
2017.08.30 07:52:45 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:45 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:45 4: AMADCommBridge (myAMADBridge) - call Dispatcher
2017.08.30 07:52:50 4: Connection accepted from myAMADBridge_192.168.178.52_59015
2017.08.30 07:52:50 4: Connection accepted from myAMADBridge_192.168.178.52_59016
2017.08.30 07:52:50 4: AMADCommBridge (myAMADBridge_192.168.178.52_59016) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "set"},"payload": {"setcmd": "myRollladenSchlafzimmerGisbert Runterfahren"}}
2017.08.30 07:52:51 4: AMADCommBridge (myAMADBridge_192.168.178.52_59016) - AMADCommBridge_CommBridge: set reading receive fhem command
2017.08.30 07:52:51 4: Connection accepted from myAMADBridge_192.168.178.52_59019
2017.08.30 07:52:51 4: Connection accepted from myAMADBridge_192.168.178.52_59020
2017.08.30 07:52:51 4: AMADCommBridge (myAMADBridge_192.168.178.52_59020) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "readingsval"},"payload": {"readingsvalcmd": "myRollladenSchlafzimmerGisbert state none"}}
2017.08.30 07:52:52 4: Connection accepted from myAMADBridge_192.168.178.52_59021
2017.08.30 07:52:52 4: Connection accepted from myAMADBridge_192.168.178.52_59022
2017.08.30 07:52:52 4: Connection accepted from myAMADBridge_192.168.178.52_59023
2017.08.30 07:52:52 4: AMADCommBridge (myAMADBridge_192.168.178.52_59023) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","volume": "50"}}
2017.08.30 07:52:52 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","volume": "50"}}
2017.08.30 07:52:52 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:52 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:52 4: AMADCommBridge (myAMADBridge) - call Dispatcher
2017.08.30 07:52:58 4: Connection accepted from myAMADBridge_192.168.178.52_59026
2017.08.30 07:52:58 4: Connection accepted from myAMADBridge_192.168.178.52_59027
2017.08.30 07:52:58 4: Connection accepted from myAMADBridge_192.168.178.52_59028
2017.08.30 07:52:58 4: AMADCommBridge (myAMADBridge_192.168.178.52_59028) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","volume": "35"}}
2017.08.30 07:52:58 5: myAMADBridge: dispatch {"amad": {"amad_id": "1503853419575","fhemcmd": "setreading"},"payload": { "flowsetVersionAtDevice": "4.0.0","volume": "35"}}
2017.08.30 07:52:58 4: AMADDevice (myAMADBridge) - ParseFn was called
2017.08.30 07:52:58 5: AMADDevice (myAMADBridge) - ParseFn was called, !!! AMAD_ID: 1503853419575
2017.08.30 07:52:58 4: AMADCommBridge (myAMADBridge) - call Dispatcher]

[Gisbert]

Interessant wäre raus zu finden was wie doppelt ausgeführt wird. Wird tatsächlich der Flow doppelt ausgeführt oder kommt das doppelte erst in FHEM zu stande.

Wie könnte ich das denn rausfinden oder unterscheiden?

[CoolTux]

Code Auswählen Erweitern


2017.08.30 07:52:50 4: AMADCommBridge (myAMADBridge_192.168.178.52_59016) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "set"},"payload": {"setcmd": "myRollladenSchlafzimmerGisbert Runterfahren"}}

Hier empfängt die Bridge den set Befehl.

Code Auswählen Erweitern


2017.08.30 07:52:51 4: AMADCommBridge (myAMADBridge_192.168.178.52_59016) - AMADCommBridge_CommBridge: set reading receive fhem command
2017.08.30 07:52:51 4: AMADCommBridge (myAMADBridge_192.168.178.52_59020) - Receive RAW Message in Debugging Mode: {"amad": {"amad_id": "1503853419575","fhemcmd": "readingsval"},"payload": {"readingsvalcmd": "myRollladenSchlafzimmerGisbert state none"}}

Und hier das dann kommende ReadingsVal. Also die Statusabfrage

Mehr ist von dem Flow nichts zu sehen. Es kommt also in der Tat nur einmal an.
Kannst du in der Tat Mal in der fhem.cfg nach Resten von AMAD suchen?
Vielleicht als erstes Mal im FHEMWEB in der Kommandozeile list TYPE=AMAD eingeben.

[CoolTux]

Wie könnte ich das denn rausfinden oder unterscheiden?

So wie gerade geschehen.
Oder wie beschrieben nach dem Trigger also parallel zum Rest des Flows eine Debug Condition

[Gisbert]

Hallo Leon

der Befehl in der Kommandozeile "list TYPE=AMAD" liefert kein Ergebnis, d.h. der Bildschirm "zuckt" eimmal, sieht dann aber wie vorher aus.
Ist der Befehl so wie angegeben richtig?

Viele​ Grüße​ Gisbert​

[CoolTux]

Ja so war er richtig. Und kannst Du bitte Deine fhem.cfg mal durchsuchen. Am besten machst ein grep

grep "AMAD" ./fhem.cfg


Grüße

[Gisbert]

Hallo Leon,

anbei das Ergebnis "grep ..."

Code Auswählen Erweitern

### AMAD - Automagic Android Device
define myAMADBridge AMADCommBridge
attr myAMADBridge fhemControlMode setControl
attr myAMADBridge room AMAD
define myNexus5 AMADDevice 192.168.178.24 1500918277207 IODev=myAMADBridge
attr myNexus5 room AMAD
attr FileLog_myNexus5 room AMAD
define myLGG6 AMADDevice 192.168.178.52 1503853419575 IODev=myAMADBridge
attr myLGG6 room AMAD
attr FileLog_myLGG6 room AMAD

Das LG G6 ist derzeit in Betrieb, das Nexus 5 ist abgeschaltet.

Viele Grüße Gisbert​

[Gisbert]

Hallo Leon,

hast du noch eine Idee was ich testen und posten könnte?
- Löschen von AMAD, Neuaufsetzen?
- Log bei Fhem-Start?
...

Viele​ Grüße​
Gisbert​

[CoolTux]

Hallo Gisbert

Ich kann absolut nichts finden was das ganze auslösen könnte. Ich glaube auch nicht das es am Flow liegt. Der scheint ja sauber alles zu machen.
Eine aktive Kopie des Flows hast Du aber nicht irgendwo, oder?

[Gisbert]

Hallo Leon,

in Antwort #4 hatte ich den Flow angehängt, aber da hattest du schon reingeschaut und nichts Auffälliges gefunden.

Wahrscheinlich ist es so, dass es noch von anderer Seite einen Befehl gibt, den ich aber noch nicht gefunden habe.

Viele​ Grüße​ Gisbert​

[CoolTux]

Hallo Gisbert,

Möglich das ich den Fehler in der Bridge gefunden habe der für das doppelte ausführen verantwortlich ist. Ich teste das heute Abend mal und gebe die Tage Bescheid.


Grüße
Leon

[CoolTux]

Fehler tatsächlich gefunden. Begebe ich Ich und Stelle ein Update bereit.

[CoolTux]

habe eine gefixte Version des Bridge Modules ins SVN geladen. Entweder ziehst Du es Dir von da oder machst morgen früh ab 8 Uhr ein Update.

Sorry für den ganzen Streß


Grüße
Leon

[tiroso]

Rein aus Interesse...was war es denn?
Super das der Fehler gefunden werden konnte.

[CoolTux]

Ich Dussel hatte den fhem() Befehl drin gelassen obwohl ich mit AnalyzeCommandChain() gearbeitet hatte. Habe ich nicht dran gedacht zu mal es damit bis vor kurzem auch noch Probleme gab.

[Gisbert]

Hallo Leon,

hab's gerade erst mitbekommen, dass du die Ursache gefunden hast.
Ich hab das Modul von deiner Seite auf Github runtergeladen, Fhem neu gestartet - und was soll ich sagen, es läuft jetzt ohne die doppelte Ausführung, ich bin total begeistert.

Vielen und lieben Dank für deine Mühe.
Gisbert