"Server wird Heruntergefahren" bei Backuperstellung

IP-Symcon
1

Moin,

habe sei ein paar Monaten einen Raspi4 mit Symcon, aktuell mit 6.0.
Ich mache Nachts um 3Uhr immer ein Backup auf einen USB Stick (Service Stop-> Ordner als ZIP auf den USB Stick → Service Start).
Heute hatte ich nun das Zweite mal das Problem das Morgens das Licht nicht mehr anging und beim aufrufen der App die Meldung kam „Server wird Heruntergefahren“.

Kann mir jemand einen Tipp geben wodran dies liegen könnte und wie ich das beheben kann?
Kann ich ein Backup im Zweifel auch erstellen ohne den Service anzuhalten?

Anbei noch die Log von heute Nacht:

15.09.2021 02:59:59 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 241 ~ Absender: RunScript
15.09.2021 02:59:59 | 51240 | MESSAGE | VariableManager      | [GeCoS_IO_V2\RTC Zeitstempel] = 1631667599
15.09.2021 02:59:59 | 19595 | DEBUG   | VariableManager      | [GeCoS_IO_V2\RTC Temperatur] = 36.7500000000
15.09.2021 02:59:59 | 55293 | MESSAGE | VariableManager      | [GeCoS_IO_V2\Letztes Keep Alive] = 1631667599
15.09.2021 02:59:59 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 241 ~ Absender: RunScript ~ Dauer: 2 ms
15.09.2021 03:00:00 | 59526 | DEBUG   | ScriptEngine         | Skriptausführung - Ereignis: 29956 ~ Absender: TimerEvent
15.09.2021 03:00:00 | 12611 | DEBUG   | VariableManager      | [>>>System\Heizung\PWMWerte\PWMBad] = 0.0000000000
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 145 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 254 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 254 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 27184 | DEBUG   | VariableManager      | [>>>System\16 OUT\GeCoS_16Out-1-38\X14 - Q.2.1.1.1] = false
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 145 ~ Absender: RunScript ~ Dauer: 4 ms
15.09.2021 03:00:00 | 00000 | CUSTOM  | 59526                | Raum 'Bad' => Soll: 18°C, Ist: 22,31°C, Aktor: 0%
15.09.2021 03:00:00 | 59526 | DEBUG   | ScriptEngine         | Skript ausgeführt - Ereignis: 29956 ~ Absender: TimerEvent ~ Dauer: 5 ms
15.09.2021 03:00:00 | 21376 | DEBUG   | ScriptEngine         | Skriptausführung - Ereignis: 36471 ~ Absender: Variable
15.09.2021 03:00:00 | 21376 | DEBUG   | ScriptEngine         | Skript ausgeführt - Ereignis: 36471 ~ Absender: Variable ~ Dauer: 0 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 223 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 275 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 275 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 279 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 279 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 261 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 261 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 265 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 265 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 265 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 265 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 265 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 265 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skriptausführung (Text) - Länge: 265 ~ Absender: RunScript
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 265 ~ Absender: RunScript ~ Dauer: 1 ms
15.09.2021 03:00:00 | 55293 | MESSAGE | VariableManager      | [GeCoS_IO_V2\Letztes Keep Alive] = 1631667600
15.09.2021 03:00:00 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 223 ~ Absender: RunScript ~ Dauer: 17 ms
15.09.2021 03:00:01 | 00000 | MESSAGE | Kernel               | Deinitialisiere...
15.09.2021 03:00:01 | 00000 | MESSAGE | ScriptEngine         | Warte auf Beendigung aller Skript-Threads...
15.09.2021 03:00:02 | 54121 | DEBUG   | VariableManager      | [>>>System\Test\UniFi DM Internet Controller\WAN1 Verfügbarkeit] = 7
15.09.2021 03:00:02 | 55204 | MESSAGE | VariableManager      | [>>>System\Test\UniFi DM Internet Controller\WAN1 Betrachtungszeitraum] = 24691
15.09.2021 03:00:02 | 00000 | DEBUG   | ScriptEngine         | Skript ausgeführt (Text) - Länge: 42 ~ Absender: TimerEvent ~ Dauer: 7118 ms

Danke.

2

Hast du eine Idee welches Skript bei dir Laufen könnte das den Beenden-Vorgang blockiert?

Nutzt du ggf. Ablaufpläne mit Wartezeit?

paresy

3

Leider nicht, Ablaufpläne habe ich noch gar keine.

Ein Tipp wie ich das rausbekommen könnte? Habe gehofft die Logdatei würde da für Klarheit schaffen.

Er versucht es ja auch um 3Uhr zu beenden, um 6Uhr hab ich dann den Stecker gezogen, da müsste das Skript ja schon sehr lang laufen. In den PHP Informationen war aber auch nichts zusehen nach einem Neustart und ist auch aktuell nichts auffällig.

4

Ähnliches Problem hatte ich auch und da nie eine Lösung für gefunden.

Letztendlich mache ich mittlerweile die Backups ohne IPS zu stoppen und das funktioniert sehr gut - ohne Restores daraus waren keine Problem.

5

Unter Windows ist es auch ohne zu stoppen möglich:

robocopy C:\ProgramData\Symcon\ D:\HiDrive\ProgramDataSymcon\ /MIR

Gruß Lutz

6

Wenn du regelmäßig Backups machst, kannst du diese ohne Herunterfahren machen. Es gibt zwar eine kleine Wahrscheinlichkeit, dass das Backup inkonsistent ist. Aber wenn du mehrere hast und damit klar kommst ein Backup im Zweifelsfall vom Vortag zu nehmen, ist dies definitiv machbar. (Ich tue es auch so, da mir Uptime wichtiger ist als im Fehlerfall ein paar wenige Daten/Zeitstempel zu verlieren)

Du kannst in der Konsole bei den PHP Informationen schauen. Die roten Zeilen sind deine Problemkinder.

paresy

7

Dann werde ich das mal erstmal ändern. Mir ist es wichtiger das ich nicht nachts in Keller laufen muss… Dann lieber ein paar Daten verlieren.

Wenn ich die Aufgabe zum Thread richtig verstehe werdet ihr das Problem in Zukunft mal abfangen?

PS: in den PHP Informationen habe ich bei mir bisher noch nie eine Rote Zeile erwischt… Kommt ja dann vielleicht mal wenn er nicht jede Nacht den Dienst durchstartet.

8

Deiner Frage entnehme ich, dass man das Beenden des Dienstes damit im Zweifel sehr lange verzögern kann. Prinzipiell besteht die Gefahr ja immer, gerade jetzt wo es keine Beschränkung der Skriptlaufzeit mehr gibt.

Macht es da vielleicht Sinn, eine optionale Wartezeit für das Beenden des Dienstes einzuführen, bei deren Überschreitung IPS die Scripte von sich aus beendet und dann weiter herunterfährt? Oder geht das nicht, weil ihr die PHP-Threads generell nicht abschießen könnt?

Gruß
Slummi

9

Korrekt. Wir können und dürfen PHP Threads nicht abschließen. Theoretisch könnten wir einfach exit aufrufen und ohne aufräumen IP-Symcon quasi absichtlich abstürzen lassen - aber so richtig gut fühlt sich das gerade nicht an.

Das kontrollieren wir gerade, ob es da evtl. noch ein Problem gibt :slight_smile:

paresy

10

4 Beiträge wurden in ein neues Thema verschoben: Die Ressource ist zur Zeit nicht verfügbar (seit 6.0)