Komisches aufzeichen von .log

gwaag · Mar 23rd 2023

Hallo,

glaube vor einigen Monaten schon mal gefragt zu haben, weiss aber leider nicht mehr wo der Beitrag zu finden ist.

Problem:

Ich habe ein Python script, das lauft wunderbar, aber in die .log Datei schreibt es pro sec. bis zu 14x, obwohl das script eigentlich nur alle 60 sec. abgearbeitet wird.

Code

Auszug aus .log

Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:13 gelbw
Woche 12 Do 23.03.2023 16:44:22 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:22 Einspeisung: -220.8 W
Woche 12 Do 23.03.2023 16:44:22 gelbw
Woche 12 Do 23.03.2023 16:44:22 gelbw

Display More

Python

#!/usr/bin/env python3

import logging
import locale
import datetime
import http.client
from influxdb import InfluxDBClient
from socket import socket, AF_INET, SOCK_DGRAM
import time
from time import sleep


while True:
    sleep(60)

# Logging
    locale.setlocale(locale.LC_ALL, 'de_DE.utf8')
    logging.basicConfig(filename='/home/pi/th/licht.log', format='%(asctime)s %(message)s', datefmt="Woche %V %a %d.%m.%Y %H:%M:%S",
    level=logging.INFO)


#  Beispiel eines logs:

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
        send_command(ZONE1_ON, IP, PORT)
        sleep(0.1)
        send_command(Gelb, IP, PORT)
        send_command(ZONE1_Dimm, IP, PORT)
        logging.info('gelbw')  # Logeintrag

# Einspeisung Influx auslesen
    client = InfluxDBClient(host='192.168.0.29', port=8086) # ip oder localhost
    client.switch_database('zaehler')

    results = client.query('SELECT "Gesamtleistung" FROM "AC" GROUP BY* ORDER BY DESC LIMIT 1')
    points = results.get_points
    point = 0
    for point in points():
        shelly_eks = point['Gesamtleistung']

    einspeisung = shelly_eks


    print ((f"Einspeisung: {round (einspeisung, 1)} W"))
    logging.info("Einspeisung: %.1f W", einspeisung)  # Logeintrag

Display More

Ist da Grundsäzlich was falsch?

Warum loged es pro durchgang nicht nur 1x?

Das script wird mit systemd gestartet und läuft zuverlässig.

Gruss und Danke

gwaag

DeaD_EyE · Mar 23rd 2023

Da der Rest vom Code fehlt, kann man das schlecht sagen.

Möglicherweise hast du ganz viele if-Bedingungen nacheinander und ein paar Bedingungen sind erfüllt.

Code

if True:
    print("Bedingung 1 erfüllt")

if True:
    print("Bedingung 2 erfüllt")

Beide Bedingungen sind wahr und beide Bedingungen führen Code aus.

Wenn man das so nicht will, sollte man mit if ... elif arbeiten.

Code

if True:
    print("Bedingung 1 erfüllt")
elif True:
    print("Bedingung 2 erfüllt")

Nur der erste Block wird ausgeführt, der zweite nicht.

gwaag · Mar 23rd 2023

Ja es sind einige if Bedingungen im code, aber es kann jeweils nur eine wahr sein.

Wenn

Code

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:

wahr ist, fuehrt es etwas aus, und es wird gelbw gelogged, aber warum in der selben 1 Sekunde bis zu 14x?

noisefloor · Mar 23rd 2023

Hallo,

sind die Zeile 41-45 richtig eingerückt? Wenn ja macht die for-Schleife in den Zeilen 38/39 wenig Sinn, in der über die Werte iteriert wird, ohne das irgendwas damit gemacht wird. Wenn da in Wirklichkeit eine Einrückung ist weißt du, woher die Logeinträge kommen, wenn `points` mehr als einen Punkt hat.

Zeile 37 und 41 sind in gezeigtem Code wenig sinnvoll bzw. überflüssig.

Wenn das Skript nur 1x pro Minute laufen soll würden ich das eher über eine systemd Timer Unit machen. Wäre auch robuster, falls das Skript mal crasht.

Wenn das Skript weiter in einer Dauerschleife laufen soll dann solltest du zumindest die Konfiguration des Loggings und das Instanzieren der DB-Verbindung vor die Dauerschleife nehmen.

Gruß, noisefloor

hyle · Mar 23rd 2023

Quote from gwaag

glaube vor einigen Monaten schon mal gefragt zu haben, weiss aber leider nicht mehr wo der Beitrag zu finden ist.

Der: Mein script loggt ca. jede Minute ?

Btw. Deine erstellten Themen findest Du hier: https://forum-raspberrypi.de/f…-thread-list/13642-gwaag/

DeaD_EyE · Mar 23rd 2023

Sowas meinte ich:

Code

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

Display More

In diesem Fall werden alle Bedingungen ausgeführt.

Code

  if winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

  elif winter and einspeisung >= -1200 and einspeisung < -0 and Ledw_ein:
      ...

Display More

Hier wird ausschließlich die erste Bedingung ausgeführt (sofern True), obwohl alle anderen Bedingungen auch wahr sind.

Wenn du viele unterschiedliche Bedingungen hast, die möglicherweise zur gleichen Zeit wahr sind und kein elif verwendest, so werden diese Bedingungen auch ausgeführt.

Es kann natürlich auch an einer falschen Einrückung liegen. Ich rate nur.

gwaag · Mar 23rd 2023

stelle hier mal das gesamte script ein.

Bitte nicht steinigen.

framp · Mar 23rd 2023

Wie DeaD_EyE schon schrieb: Du solltest elif nutzen um mehrfaches Matchen zu verhindern. Dadurch kommen die mehrfachen Ausgaben. Auch wuerde ich ein

Code

if sommer 
   if ...
      .....
else # winter 
   if  ...
       .....

nutzen. Das liest sich leichter und besser

DeaD_EyE · Mar 23rd 2023

sommer und winter könnten eigene Bedingungen sein.

Das Problem ist das, was ich vermutet habe. Es können mehrere Bedingungen zutreffen, was zu den Logging-Einträgen führt.

gwaag · Mar 24th 2023

Habe jetzt die script angepasst.

Script WP-Steuerung is jetzt eigentlich ok, Script PV-Licht ist etwas besser geworden, logged aber immer noch das Gleiche 5-6x obwohl der Eintrag alle 60 sec nur 1x erfolgen sollte .

Habe mal die Files angehaengt, ev habe ich ja noch was nicht richtig verstanden und es ist immer noch etwas falsch.

Danke und Gruss

gwaag

Dennis89 · Mar 24th 2023

Hallo,

hatten wir da nicht schon mal so ein Problem, dass da mehrere Skripte gleichzeitig gelaufen sind?

Wie startest du die? Bitte das System-Unit oder den Crontab-Eintrag hier im Code-Block zeigen.

Wäre auch schön wenn man die Skripte nicht immer herunter laden müsste.

Konfiguriere deine Logeinträge einmal vor der Schleife und nicht in jedem Durchgang.

Grüße

Dennis

gwaag · Mar 24th 2023

Ja das hatten wir schon mal. Habe aber vor langer Zeit die Logs separat, pro script gemacht.

Hier die beiden System-Units ( Mit systemctl status, laufen beide.)

Code

[Unit]
Description=PV_Licht
After=multi-user.target
Wants=network-online.target
StartLimitIntervalSec=300
StartLimitBurst=5

[Service]
Type=simple
ExecStart=/usr/bin/python3 /home/pi/th/PV_Licht.py
Restart=on-failure
RestartSec=1s

[Install]
WantedBy=multi-user.target

Display More

Code

[Unit]
Description=WP-Steuerung
After=multi-user.target
Wants=network-online.target
StartLimitIntervalSec=300
StartLimitBurst=5


[Service]
Type=simple
ExecStart=/usr/bin/python3 /home/pi/th/WP-Steuerung.py
Restart=on-failure
RestartSec=1s

[Install]
WantedBy=multi-user.target

Display More

Crontab-Einträge gibt es nicht.

" Konfiguriere deine Logeinträge einmal vor der Schleife und nicht in jedem Durchgang."

Das verstehe ich nicht, wie muss ich das machen?

Danke

gwaag

Dennis89 · Mar 24th 2023

Du startest eine Schleife und dann nach dem 'sleep' konfigurierst du in jedem Durchgang dein Logging. Die drei Zeilen kannst du vor die Schleife setzen.

Ich denke zwar nicht, dass das das Problem löst, aber es ist unnötig in jedem Durchgang das Logging neu zu konfigurieren. (Und noch einiges mehr, aber das reicht mir gerade nicht)

Wenn ich dein Skript nehme und die ganzen Abfragen rauswerfe und die Schleife mit dem Log-file-schreiben laufen lasse, dann kann ich dein Verhalten nicht nachstellen. Ich denke der Fehler ist "im" System zu suchen. Da müssen sich dann aber andere zu Wort melden.

Viel Erfolg

Dennis

gwaag · Mar 24th 2023

Danke Dennis89.

ist gemacht, beobachte weiter und melde mich spaeter wieder.

gwaag · Mar 24th 2023

Macht immer noch zu viele Einträge.

Python

#!/usr/bin/env python3

import logging
import locale
import datetime
from datetime import datetime
import http.client
from influxdb import InfluxDBClient
from socket import socket, AF_INET, SOCK_DGRAM
import time
from time import sleep

# Logging
locale.setlocale(locale.LC_ALL, 'de_DE.utf8')
logging.basicConfig(filename='/home/pi/th/licht.log', format='%(asctime)s %(message)s', datefmt="Woche %V %a %d.%m.%Y %H:%M",
level=logging.INFO)


while True:
    sleep(60)


# Zeitfenster
    now = datetime.now()
    Now = now.hour * 60 + now.minute

Display More

Code

Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -41.5 W
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:02 Einspeisung: -81.4 W
Woche 12 Fr 24.03.2023 13:02 gelbw
Woche 12 Fr 24.03.2023 13:03 Einspeisung: -81.4 W
Woche 12 Fr 24.03.2023 13:03 Einspeisung: -81.4 W
Woche 12 Fr 24.03.2023 13:03 gelbw
Woche 12 Fr 24.03.2023 13:03 Einspeisung: -81.4 W

Display More

gwaag · Mar 24th 2023

So, jetzt gehts.

Habe die Schleife raus, und alles wieder richtig eingerückt, und starte jetzt das script mit crontab -e, jede Minute.(später wird das noch in Timer Unit geändert)

Log zeigt jetzt Eintrag pro Ereignis nur noch 1x pro minute.

Danke an alle.

Gruss

gwaag