"Verzögerungen"? - IPS 5 - Raspberry

Hi,

vielleicht kann mir jemand auf die Sprünge helfen… seit einiger Zeit (ca. 1,5 Wochen) ist mein IPS deutlich langsamer. Nicht beim Schalten oder im Webfront oder so, sondern beim Verarbeiten von eingehenden Nachrichten insb. von Enocean.

Protokolle die nahezu sofort nach dem Versenden des Schaltbefehls als „Antwort“ kommen benötigen teilweise sehr lange bis sie sich auswirken (also z.B. in der Konsole das Ergebnis sichtbar wird) oder werden gar nicht verarbeitet. Erst hatte ich Enocean also meine FAM14 in verdacht, aber dank eines 2. USB-Sticks kann ich sehen das die Protokolle kommen und auch schnell aber IPS sie verschluckt oder spät verarbeitet. Das führt vor allem zu fehlerhaften Betriebszuständen (realer STatus und Status in IPS laufen auseinander). Der Raspberry langweilt sich, Speicherverbrauch ist auch im Rahmen, daher schließe ich den RPI erstmal als Problem aus… hat jemand eine Idee wie ich der Sache näher kommen kann?

Schau mal in den PHP Informationen, ob dort Skripte die Ausführung blockieren.

paresy

Also ich hab jetzt nochmal mitgeschnitten ohne Ende… die Antwort die direkt nach dem Schalten kommt häufig nicht im IPS richtig an. Im FTDI sehe ich das Telegramm, im Enocean-Gateway auch noch, im Gerät kommt es nicht an. Mein Script wiederholt dann nach einiger Zeit nochmal, wenn kein Response kommt, da hat es diesmal geklappt, manchmal verschluckt er aber auch da die Response…

Die PHP-Threads langweilen sich, da ist nichts los… da dort aber dann mehr auf einmal passiert kann ich es natürlich nicht genau nachverfolgen welche Threads da laufen, ein Log gibt es dafür ja nicht oder?

Kannst du das hier für mich „entschlüsseln“? Ist ein Teil des Enocean-Gatesway-Debug, aber zu dieser Zeit gab es KEIN Telegram in der Luft (laut Tracer auf anderem Rechner):

Das letzte Telegramm war 11:45:20.0006 also vor 11 Sekunden

Wir fragen (ich glaube alles 60 Sekunden) die BaseID ab, einfach um auch die Kommunikation zu prüfen.

Magst du mal in den Spezialschalter MessageQueueWatch einschalten? Kommen da lila Nachrichten? Das würde bedeuten dass intern etwas auf der Message Queue blockiert.

paresy

Hab ich gemacht, leider nichts in lila (und auch nichts anderes zu der Zeit):

Hier mal ein ganzer Debug, nicht das ich was falsch mache:

FTDI-DEBUG:


TXT: 20.09.2018 14:43:01.00 |             TRANSMIT | U<NUL><BEL><BEL><SOH>zö0ÿ?F=0<ETX>ÿÿÿÿÿ<NUL>O
HEX: 20.09.2018 14:43:01.00 |             TRANSMIT | 55 00 07 07 01 7A F6 30 FF 97 46 3D 30 03 FF FF FF FF FF 00 4F 
TXT: 20.09.2018 14:43:01.00 |             RECEIVED | U<NUL><SOH><NUL><STX>e<NUL><NUL>
HEX: 20.09.2018 14:43:01.00 |             RECEIVED | 55 00 01 00 02 65 00 00 

^^^Sendet gedrückt


TXT: 20.09.2018 14:43:01.00 |             TRANSMIT | U<NUL><BEL><BEL><SOH>zö<NUL>ÿ?F= <ETX>ÿÿÿÿÿ<NUL>ù
HEX: 20.09.2018 14:43:01.00 |             TRANSMIT | 55 00 07 07 01 7A F6 00 FF 97 46 3D 20 03 FF FF FF FF FF 00 F9 
TXT: 20.09.2018 14:43:01.00 |             RECEIVED | U<NUL><SOH><NUL><STX>e<NUL><NUL>
HEX: 20.09.2018 14:43:01.00 |             RECEIVED | 55 00 01 00 02 65 00 00 

^^^^ Sendet losgelassen

TXT: 20.09.2018 14:43:07.00 |             TRANSMIT | U<NUL><SOH><NUL><ENQ>p<BS>8
HEX: 20.09.2018 14:43:07.00 |             TRANSMIT | 55 00 01 00 05 70 08 38 
TXT: 20.09.2018 14:43:07.00 |             RECEIVED | U<NUL><ENQ><SOH><STX>Û<NUL>ÿ?F<NUL>ÿ<ENQ>
HEX: 20.09.2018 14:43:07.00 |             RECEIVED | 55 00 05 01 02 DB 00 FF 97 46 00 FF 05 

^^^^^Empfängt Rückmeldung

Enocean-Gateway-Debug:


TXT: 20.09.2018 14:43:01.00 |             TRANSMIT | U<NUL><BEL><BEL><SOH>zö0ÿ?F=0<ETX>ÿÿÿÿÿ<NUL>O
HEX: 20.09.2018 14:43:01.00 |             TRANSMIT | 55 00 07 07 01 7A F6 30 FF 97 46 3D 30 03 FF FF FF FF FF 00 4F 
TXT: 20.09.2018 14:43:01.00 |         Parse Buffer | U<NUL><SOH><NUL><STX>e<NUL><NUL>
HEX: 20.09.2018 14:43:01.00 |         Parse Buffer | 55 00 01 00 02 65 00 00 
TXT: 20.09.2018 14:43:01.00 |       Parse Data: 02 | <NUL>
HEX: 20.09.2018 14:43:01.00 |       Parse Data: 02 | 00 
TXT: 20.09.2018 14:43:01.00 |               RESULT | <NUL>
HEX: 20.09.2018 14:43:01.00 |               RESULT | 00 

^^^^^^ Sendet gedrückt

TXT: 20.09.2018 14:43:01.00 |             TRANSMIT | U<NUL><BEL><BEL><SOH>zö<NUL>ÿ?F= <ETX>ÿÿÿÿÿ<NUL>ù
HEX: 20.09.2018 14:43:01.00 |             TRANSMIT | 55 00 07 07 01 7A F6 00 FF 97 46 3D 20 03 FF FF FF FF FF 00 F9 
TXT: 20.09.2018 14:43:01.00 |         Parse Buffer | U<NUL><SOH><NUL><STX>e<NUL><NUL>
HEX: 20.09.2018 14:43:01.00 |         Parse Buffer | 55 00 01 00 02 65 00 00 
TXT: 20.09.2018 14:43:01.00 |       Parse Data: 02 | <NUL>
HEX: 20.09.2018 14:43:01.00 |       Parse Data: 02 | 00 
TXT: 20.09.2018 14:43:01.00 |               RESULT | <NUL>
HEX: 20.09.2018 14:43:01.00 |               RESULT | 00 

^^^^ Sendet losgelassen


TXT: 20.09.2018 14:43:07.00 |             TRANSMIT | U<NUL><SOH><NUL><ENQ>p<BS>8
HEX: 20.09.2018 14:43:07.00 |             TRANSMIT | 55 00 01 00 05 70 08 38 
TXT: 20.09.2018 14:43:07.00 |         Parse Buffer | U<NUL><ENQ><SOH><STX>Û<NUL>ÿ?F<NUL>ÿ<ENQ>
HEX: 20.09.2018 14:43:07.00 |         Parse Buffer | 55 00 05 01 02 DB 00 FF 97 46 00 FF 05 
TXT: 20.09.2018 14:43:07.00 |       Parse Data: 02 | <NUL>ÿ?F<NUL>
HEX: 20.09.2018 14:43:07.00 |       Parse Data: 02 | 00 FF 97 46 00 
TXT: 20.09.2018 14:43:07.00 |     Parse Data (Opt) | ÿ
HEX: 20.09.2018 14:43:07.00 |     Parse Data (Opt) | FF 
TXT: 20.09.2018 14:43:07.00 |               RESULT | <NUL>ÿ?F<NUL>
HEX: 20.09.2018 14:43:07.00 |               RESULT | 00 FF 97 46 00 

^^^^^Empfängt Ergebnis


und im Aktor kommt nichts an.

Hier die Sicht aus dem DolphinViewer:

Gibt es noch etwas was ich kontrollieren kann? Ich habe auch den erfolgreichen Durchlauf des selben Aktors mitgetraced… sieht identisch aus, nur das beim Aktor was an kommt… ich weiß so langsam nicht mehr weiter, meine Status stimmen mittlerweile wohl keine mehr und meine Sicherheitsabfragen wegen nicht erfolgreichem Schalten fluten mein Handy…