IPS gecrashed?

[galleto]

Moin,

die Nacht ist mit meinem IPS irgend etwas Ungutes passiert, folgendes Fehlerbild:

Der Dienst war heute Morgen einfach beendet (das Symbol in der Taskleiste grau). Ich konnte den Dienst zwar neu starten und kann auch die Konsole aufrufen, aber es erscheint dabei die Fehlermeldung „No class registered for invokable interface IIPSModule.“ Auf den ersten Blick sind alle Instanzen mit einem roten Ausrufezeichen versehen. Ein Zugriff über das WF ist nicht möglich. Irgenwie werkelt IPS aber noch, denn die Variablen der Wetterstation wurden weiter mit Daten aktualisiert.

Diesen Thread hab ich gesehen, aber er bringt mich erstmal nicht weiter. Ein Neustart hat nicht geholfen. An die Logs komme ich erst heute Abend ran.

Rahmenbedingungen: System ist Windows 7 auf einem MSI Wind Box DC110. Ein Backup wurde die Nacht noch gefahren, aber ob es brauchbar ist? Letzte Aktionen gestern waren SSL-Spielereien am Webfront (einfach nur paar mal de-/aktiviert) und die Installation von NetDrive auf dem System. Die Installation und Konfiguration des IPS-NetPlayers und das Löschen von Tonis SoundDevice-Instanz halte ich für unkritisch. IPS hat gestern Abend noch völlig korrekt das Haus in den Schlafmodus gebracht.

Lässt sich anhand der Angaben schon sagen, in welche Richtung ich jetzt marschieren darf? Settings reparieren, Backup einspielen oder (mein Favorit ) „einfach nur xyz machen“?

Dank und Grüße
galleto

[sanipeter]

Hi!

Ich hatte mal ein ähnliches Verhalten bei einem anderen Serverdienst (nicht IPS).
Kann es sein, dass deine Festplatte voll ist?

LG,
Peter

[kronos]

Wenn Du an IPS an sich nichts geändert hast müsste die Settings.json ja unverändert geblieben sein. Ist die aktuelle identisch zu der eines funktionierenden Backups? Wenn nein würde ich zunächst diese mal zurück sichern.

Im Logfile würde ich dann auch noch graben um der Ursache auf die Spur zu kommen.

[galleto]

Danke für Eure Hilfe. Es war beides nicht, stattdessen haben mich zwei Probleme getroffen. Eins ist gelöst, die eigentliche Ursache - wahrscheinlich der Windows-Time-Service - aber noch nicht. Aber vielleicht besser von vorn.

Ich war heute früh schon nahe dran:

@galleto

das Löschen von Tonis SoundDevice-Instanz halte ich für unkritisch

Das war ein Irrtum. Wie das Log des morgentlichen Neustartversuchs zeigt, bringt die gelöschte Instanz zusammen mit der im Modules-Ordner verbliebenen DLL das System aus dem Tritt.

[...]
22.07.2013 06:20:20.395 |     0 | MESSAGE | ModuleLoader         | #Geladenen WebFront Configurator
22.07.2013 06:20:20.395 |     0 | MESSAGE | ModuleLoader         | Lade Native-Bibliotheken
22.07.2013 06:20:20.597 |     0 | MESSAGE | SoundDevice          | Register
22.07.2013 06:20:20.598 |     0 | MESSAGE | ModuleLoader         | #Geladenen SoundDevice
22.07.2013 06:20:20.598 | 12790 | MESSAGE | SoundDevice          | Creating...
22.07.2013 06:20:20.659 | 12790 | MESSAGE | SoundDevice          | Deleting...
22.07.2013 06:20:20.664 |     0 | ERROR   | ModuleLoader         | Fehler beim Laden der Native-Bibliothek: SoundDevice.dll, Fehler: Fehler beim Laden der Native-Bibliothek: C:\IP-Symcon\modules\SoundDevice.dll, Fehler: Konnte Instanz #12790  nicht erstellen: Fehler: Error : Unable to instantiate device enumerator
22.07.2013 06:20:20.664 |     0 | MESSAGE | ModuleLoader         | Lade PHP-Bibliotheken
22.07.2013 06:20:20.665 | 40403 | MESSAGE | WWW Reader           | Erstelle...
22.07.2013 06:20:20.670 | 40403 | MESSAGE | WWW Reader           | Lösche...
22.07.2013 06:20:20.670 | 40403 | ERROR   | InstanceManager      | Konnte Instanz #40403  nicht erstellen: Fehler: Duplicate function name
22.07.2013 06:20:20.670 | 35817 | MESSAGE | WWW Reader           | Erstelle...
22.07.2013 06:20:20.674 | 35817 | MESSAGE | WWW Reader           | Lösche...
22.07.2013 06:20:20.674 | 35817 | ERROR   | InstanceManager      | Konnte Instanz #35817  nicht erstellen: Fehler: Duplicate function name
[...]
22.07.2013 06:20:20.691 | 45428 | ERROR   | Serial Port          | Access violation at address 00571C0E in module 'ips.exe'. Read of address 0000004B
22.07.2013 06:20:20.692 | 45428 | MESSAGE | Serial Port          | Lösche...
22.07.2013 06:20:20.692 | 45428 | ERROR   | InstanceManager      | Konnte Instanz #45428  nicht erstellen: Fehler: Duplicate function name
22.07.2013 06:20:20.692 | 39991 | MESSAGE | Serial Port          | Erstelle...
22.07.2013 06:20:20.696 | 39991 | ERROR   | Serial Port          | Access violation at address 00571C0E in module 'ips.exe'. Read of address 0000004B
22.07.2013 06:20:20.697 | 39991 | MESSAGE | Serial Port          | Lösche...
22.07.2013 06:20:20.697 | 39991 | ERROR   | InstanceManager      | Konnte Instanz #39991  nicht erstellen: Fehler: Duplicate function name
[...]

DLL gelöscht, IPS-Neustart, alles gut.

Nur der Crash ist noch unklar. Das IPS-Log endet ziemlich genau 1 Uhr, genauer 1:00:16:

[...]
22.07.2013 01:00:13.519 | 41072 | MESSAGE | VariableManager      | [IPS 868 Serial Port\Tracker A\Update] = 1374447614
22.07.2013 01:00:16.000 | 38324 | DEBUG   | ExecuteThreadID #10  | Skriptausführung: 38324.ips.php ~ Absender: Ereignis #48048, Zeit Ereignis
22.07.2013 01:00:16.031 |     0 | DEBUG   | ExecuteThreadID #4   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 3562 ms
22.07.2013 01:00:16.072 | 45960 | MESSAGE | VariableManager      | [Program\System\RS Monitoring DB & Settings\Überwachung Logging.db + Settings\Timestamp logging.db] = 1374447578
22.07.2013 01:00:16.072 | 56201 | DEBUG   | VariableManager      | [Program\System\RS Monitoring DB & Settings\Überwachung Logging.db + Settings\Timestamp settings] = 1374447420
22.07.2013 01:00:16.074 | 35196 | DEBUG   | VariableManager      | [Program\System\RS Monitoring DB & Settings\Überwachung Logging.db + Settings\Warnstatus logging.db] = False
22.07.2013 01:00:16.077 | 38324 | MESSAGE | ExecuteThreadID #10  | [Program\System\RS Monitoring DB & Settings\Überwachung Logging.db + Settings\RS Monitoring] = 
 #0000,  Script 38324, kein Alarm: logging.db(Alter: 38 sec) und settings(Alter: 196 sec) sind aktuell

22.07.2013 01:00:16.077 | 38324 | DEBUG   | ExecuteThreadID #10  | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 76 ms
22.07.2013 01:00:16.112 |     0 | DEBUG   | ExecuteThreadID #10  | Skriptausführung: ips.php ~ Absender: WebInterface
22.07.2013 01:00:16.173 |     0 | DEBUG   | ExecuteThreadID #10  | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 61 ms
22.07.2013 01:00:16.228 |     0 | DEBUG   | ExecuteThreadID #10  | Skriptausführung: ips.php ~ 

Windows bestätigt das IPS-Ende um diese Zeit:

Die Ereignisanzeige meldet zu der Zeit auch einen aktiven Time-Service:

Kann das die Ursache des eigentlichen Absturzes gewesen sein?
Wie könnte man sich davor zukünftig schützen - außer durch Deaktivierung des Time-Service?

Und zum Schluss: Ist folgende Sicherheitswarnung unbedenklich?

Dank und Grüße
galleto

[galleto]

@galleto

Die Ereignisanzeige meldet zu der Zeit auch einen aktiven Time-Service
Kann das die Ursache des eigentlichen Absturzes gewesen sein?

Hab noch etwas gefunden, es ist tatsächlich die Zeit geändert worden:

(Wie) Kann die Änderung der Systemzeit durch den Time-Service den IPS-Dienst deaktiveren/crashen?

Grüße
galleto

[kronos]

Na ja, ich könnte mir schon vorstellen, dass eine Software die Timerfunktionen in vielfältiger Weise benutzt durch massive Sprünge in der Systemzeit durcheinander kommen kann.

Die Frage müsste daher eigentlich richtig lauten warum Deine Systemzeit springt und nicht wie die daraus resultierenden Folgen sich auswirken.

[galleto]

@kronos

Die Frage müsste daher eigentlich richtig lauten warum Deine Systemzeit springt und nicht wie die daraus resultierenden Folgen sich auswirken.

Die Zeit ist unterhalb der Tausendstel-Grenze korrigert worden (s.o.). Das dürfte ein normaler Vorgang sein und IPS m.E. nicht ins Schleudern bringen.

Es kann natürlich sein, dass die vorübergehende Deaktivierung von IPS unter bestimmten Umständen normal ist. Ist das so?

Grüße
galleto

[kronos]

(Wie) Kann die Änderung der Systemzeit durch den Time-Service den IPS-Dienst deaktiveren/crashen?

Solche Änderungen der Systemzeit sind normal. Die Differenz ist ja minimal und der Ungenauigkeit der systemeigenen Uhr geschuldet. Solche Einträge solltest Du daher durchaus häufiger in Deinem LOG finden. Ich glaube nicht, dass IPS in diesem Fall deswegen aus dem Tritt gekommen ist.

Meine Antwort bezog sich auf größere Abweichungen bei denen ich mir ungute Reaktionen vorstellen könnte. Deswegen werden größere Abweichungen auch gerne in vielen kleinen Schritten nach und nach korrigiert und nicht mit einem großen.

[galleto]

@kronos

Ich glaube nicht, dass IPS in diesem Fall deswegen aus dem Tritt gekommen ist.

Dann wird die plörtzliche Deaktivierung des IPS-Dienstes wohl ein Rätsel bleiben, denn mehr Infos habe ich nicht.

Hoffen wir, dass es einmalig bleibt.

Danke für alle Antworte!

Grüße
galleto