DutyCycle - Analyse des Logfiles

[manfredh]

Mein DC liegt normalerweise tagsüber bei 15-20, wenn Ruhe im Haus ist eher unter 10%. Seit einigen Wochen gibt es jedoch Tage, wo er auf bis zu 60% oder darüber steigt. Es gibt im Tagesablauf keinen erkennbaren Anlass dafür.

Nach einigen halbherzigen Versuchen (das System lief ja immer klaglos weiter, da der DC nie in die Nähe von 100% kam), will ich der Sache nun endlich auf die Spur kommen.

Erste Maßnahmen waren das sukzessiver Außer Betrieb nehmen von Geräten und Programmen. Daher sind nun nahezu alle batteriebetriebenen Geräte und ausnahmslos alle Programme (ca. 110) außer Betrieb. Einiges muss leider in Betrieb bleiben, weil in einem kleinen Teil des Hauses ein Geschäft mit Publikumsverkehr läuft.

Nachdem nun heute früh der DC wieder für ca. 1 Stunde auf > 30 gestiegen war, nahm ich mir vor, die Ursache per Analyse des Logfiles zu finden.

Gesagt, getan - dieser Thread half mir beim Einstieg. Etwas anderes verwertbares habe ich leider nicht gefunden.

Das Logging steht auf "Alles loggen".

Erste Fragen: "rfd" steht für Classic? wird hier auch IP-Funk geloggt?

Mit dem Logfile komme ich nun auch nicht weiter, vermutlich mangels näherem Wissen, das ich nun von euch erhoffe:

Ich finde in den Aufzeichnungen von 4 Uhr bis jetzt (ca. 9:40) nur 7 mal die Zeichenkette "rfd: TX:":

Code: Alles auswählen

Line 29990: Jul  1 06:39:59 homematic-ccu2 user.debug rfd: TX:  @1616761775 0x41171A -> 0x1B34F9 CENTRAL_RAMP_START [QEQ0692353]:   CNT=69,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 200   RAMPTIME = 0   ONTIME = 0
	Line 38879: Jul  1 07:27:21 homematic-ccu2 user.debug rfd: TX:  @1619603552 0x41171A -> 0x1B34F9 CENTRAL_RAMP_START [QEQ0692353]:   CNT=78,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 0   RAMPTIME = 0   ONTIME = 0
	Line 54298: Jul  1 08:49:36 homematic-ccu2 user.debug rfd: TX:  @1624538454 0x41171A -> 0x1D80D1 Generic [QEQ0692353]:   CNT=54,RPTEN=1,RPTED=0,BIDI=1,BURST=1,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   DATA = 82 12 10 
	Line 54322: Jul  1 08:49:38 homematic-ccu2 user.debug rfd: TX:  @1624540392 0x41171A -> 0x1D80D1 Generic [QEQ0692353]:   CNT=63,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   DATA = 82 12 0F 
	Line 54346: Jul  1 08:49:38 homematic-ccu2 user.debug rfd: TX:  @1624540839 0x41171A -> 0x1D80D1 Generic [QEQ0692353]:   CNT=72,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   DATA = 82 12 10 
	Line 54885: Jul  1 08:52:13 homematic-ccu2 user.debug rfd: TX:  @1624695270 0x41171A -> 0x1D80D1 Generic [QEQ0692353]:   CNT=81,RPTEN=1,RPTED=0,BIDI=1,BURST=1,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   DATA = 82 12 11 
	Line 57543: Jul  1 09:06:15 homematic-ccu2 user.debug rfd: TX:  @1625537405 0x41171A -> 0x1D80D1 Generic [QEQ0692353]:   CNT=90,RPTEN=1,RPTED=0,BIDI=1,BURST=1,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   DATA = 82 12 12 
	Line 61783: Jul  1 09:34:00 homematic-ccu2 user.debug rfd: TX:  @1627202530 0x41171A -> 0x1CD772 CENTRAL_RAMP_START [QEQ0692353]:   CNT=67,RPTEN=1,RPTED=0,BIDI=1,BURST=0,WAKEUP=0,WAKEMEUP=0,BCAST=0,TYPE=0x11   CHANNEL = 1   LEVEL = 196   RAMPTIME = 0   ONTIME = 0

Warum finde ich das manuelle Öffnen (per WebUi) von insgesamt 15 Rollläden hier nicht?

Gibt es weitere Strings, mit denen ich Sende(versuche) der Zentrale finden kann?

Da ich die Seriennummer QEQ0692353 nirgends finde - ist wohl die des Sendemoduls? Wo kann ich das verifizieren, ohne die RaspMatic zu öffnen?

Übrigens: der DC ist inzwischen wieder auf 11 gesunken.

[MichaelN]

Ich weiß nicht, ob das Log dabei hilfreich ist.
Erstmal die Standardfrage: es läuft (lief) nicht zufällig grade ein FW-Update?

Ansonsten kann der DC ja nur steigen, wenn die Zentrale sendet. Direktverknüpfungen sind also raus.
Bleiben noch die Programme. Einfach unter Status / Programme schauen, wo sich der Zeitstempel ständig aktualisiert. Das sind die ersten Verdächtigen.

[Baxxy]

Ein Analyzer wäre hier Gold wert. Damit bliebe dir zumindest die Rumsucherei in den Logfiles erspart. Die klassische SN des Funkmoduls sollte u.a. mein Analysescript ausgeben.

[manfredh]

Ich weiß nicht, ob das Log dabei hilfreich ist.

Ich dachte, dort kann man alle Sendeversuche der Zentrale sehen?
...und mit einem ordentlichen Editor (notepad++) auch sauber filtern.

Erstmal die Standardfrage: es läuft (lief) nicht zufällig grade ein FW-Update?

Definitiv nicht.
Zumal der DC ja zwischenzeitig immer mal wieder für viele Stunden auf unter 10 geht.

Ansonsten kann der DC ja nur steigen, wenn die Zentrale sendet. Direktverknüpfungen sind also raus.

Sehe ich auch so.

Bleiben noch die Programme. Einfach unter Status / Programme schauen, wo sich der Zeitstempel ständig aktualisiert. Das sind die ersten Verdächtigen.

Alle Programme sind deaktiviert.
...hoppla - 9 systeminterne jetzt zusätzlich deaktiviert.

[manfredh]

Ein Analyzer wäre hier Gold wert. Damit bliebe dir zumindest die Rumsucherei in den Logfiles erspart. Die klassische SN des Funkmoduls sollte u.a. mein Analysescript ausgeben.

Erst mal danke für das Analysscript. Die Adresse ist tatsächlich die des Funkmoduls.

Mit Analyser meinst du die Hardware, von der ich hier das eine oder andere mal gelsen habe? Werde ich mir mal ansehen.

Allerdings: wie im Eröffnungspost geschrieben, gibt es nicht viel Rumsucherei. Ich finde ja keine Sendeeinträge bzw. nicht die, die zumindest zwingend drin sein müssten. Was mache ich da falsch?

[Baxxy]

Mit Analyser meinst du die Hardware, von der ich hier das eine oder andere mal gelsen habe?

Ja genau, die aus der "Hardwareabteilung". Die XS-Version kann man recht schnell und günstig aus einem NanoCUL "herstellen".
Hatte da auch mal eine Flash-Anleitung für Windows gemacht (Link in der Signatur)

Was mache ich da falsch?

Hmm, hattest du deine Zentrale neu gestartet nachdem du das Loglevel auf "alles loggen" gestellt hattest?
Ich habe da im Hinterkopf das erst nach reboot wirklich alles geloggt wird.

Wenn ich einen HM-Aktor aus der WebUI schalte sehe ich dann sowas...

Code: Alles auswählen

Jul  1 23:24:58 Test-CCU-RMatic-Pi3Bplus user.debug multimac: A>: #33 HmBidcos TxTelegram [burst] #46[BiDi|WOR|Ren] FF7E0C->5FFC8F Configuration: 02 0E
Jul  1 23:24:59 Test-CCU-RMatic-Pi3Bplus user.debug multimac: A>: #34 HmBidcos TxTelegram [] #4F[BiDi|Ren] FF7E0C->5FFC8F Configuration: 03 0E
Jul  1 23:24:59 Test-CCU-RMatic-Pi3Bplus user.debug multimac: A>: #35 HmBidcos TxTelegram [] #58[BiDi|Ren] FF7E0C->5FFC8F Configuration: 04 0E
Jul  1 23:25:13 Test-CCU-RMatic-Pi3Bplus user.debug multimac: A>: #37 HmBidcos TxTelegram [burst] #61[BiDi|WOR|Ren] FF7E0C->5FFC8F CentralCommand: 02 01 C8 00 00
Jul  1 23:25:38 Test-CCU-RMatic-Pi3Bplus user.debug multimac: A>: #38 HmBidcos TxTelegram [burst] #6A[BiDi|WOR|Ren] FF7E0C->5FFC8F CentralCommand: 02 01 00 00 00

Aber das zu filtern und zu analysieren ist schon ne harte Nuss. Und für IP wüsste ich jetzt erstmal gar nicht wie man da an vernünftige Daten kommt.

[manfredh]

Das Logging steht schon seit ewigen Zeiten auf "Alles loggen".

Wie auch immer, ich habe gerade einen Stick bestellt. Werde berichten...

[manfredh]

Inzwischen habe ich einen Stick für den Asksin Analyser XP und die Aufzeichnung läuft.

Sobald der DC nun wieder in die Höhe geht, werde ich den Funkverkehr analysieren und berichten.

Danke erst mal für die Hilfe und die Tipps.

[Baxxy]

und die Aufzeichnung läuft

Konntest du inzwischen den DC-Übeltäter identifizieren?

Grüße
Baxxy

[manfredh]

Ja. Sorry, dass ich keine Rückmeldung gegeben habe.

Es war ein Bewegungsmelder HmIP-SMI. Den hatte ich vorübergehend als Helligkeitssensor auf dem balkon missbraucht, weil der SLO kaputt war. Die Einstellungen waren DC-fressend, wenn z.B. durch Wind oder häufig wechselnde Helligkeit zu oft gesendet wurde.

Bei der Gelegenheit fällt mir ein anderer Fall ein, der kürzlich aufgetreten ist: der DC ging hoch und viele Geräte haben Kommunikationsstörung gemeldet. CS war auf 100.

Die Ursache konnte nicht durch den Analyser gefunden werden. Es war ein HM-LC-Bl1PBU-FM - durch Zufall entdeckt, weil er sich nicht mehr bedienen ließ. Spannung kurz werg und er ging wieder, CS dann bei 1%.

Ist wohl ein Kandidat zum Tauschen des C26.