IPS bleibt beim Herunterfahren hängen

ckerndl · 12. Juli 2010 um 14:55

Hi,

ich leider in der letzten Zeit feststellen das der IPS Dienst ips.exe hängt.

Nächtlich wird bei mir der Rechner neugestartet jedoch kommt es ca einmal in 2 Wochen vor das der Rechner bei Windows wird heruntergefahren hängt.
Ich dachte erst an ein Windows Problem jedoch ist mir hier der Zufall zur Hilfe gekommen.
Die IPS.exe war im laufenen Windows nicht zu Beenden nicht über das IPS tray nicht über den tastmanager also wirklich gar nicht selbst kleine Tools welche Dienste angeblich wirklich killen können brachten keinen Erfolg.

Status des Rechners.
ips.exe ließ sich nicht beendet arbeitete wohl aber noch intern.
Werte in Dashboard wurden nicht aktualisiert auch nach neustart Dashboard
in den Logfiles war gar nichts zu finden.

Dann habe ich windows manuell heruntergefahren und dann hing der Rechner bei „Windows wird heruntergefahren“

Leider kommt dieses Phänomen nicht nur bei mir vor sondern auch bei Tiefflieger2000 und auch bei meinen 2 anderen IPS Rechnern welche eine ganz andere Konfiguration haben und (noch) nichts miteinander zu tun haben.

Ich weiß nun leider keinen Rat mehr.

Gruß
Christian

immergut · 16. Juli 2010 um 11:42

Hallo,
das gleiche Phänomen habe ich auch. Der IPS-Dienst kann nicht beendet werden.
Es kommt auch häufig vor, dass wenn ich den Rechner in den Standby schicke, er nach dem Aufwecken kurz da ist und sich dann aber mit einem Bluesreen verabschiedet und neu startet. Es ist also nicht mehr möglich den Rechner in Standby zu setzen und morgens wieder zu starten, da der IPS-Dienst nicht richtig beendet oder gestartet werden kann. (Phänomen tritt erst seit der Installation von IPS auf). Als Betriebssystem verwende ich Vista Home Premium 32bit.
Bitte um Hilfe.

meike0_0 · 16. Juli 2010 um 12:10

Hallo Christian,

verwendest Du DUG-Tool, wenn ja schalte sie ab.
Das hat bei mir geholfen.

immergut · 16. Juli 2010 um 13:07

Hallo meike0_0,
verwende kein DUG-Tool. Daran kann es leider nicht liegen.
Trotzdem vielen Dank für Deine schnelle Antwort.

ckerndl · 16. Juli 2010 um 17:06

Nein verwende ich auch nicht.

paresy · 16. Juli 2010 um 18:57

Entsprechende logfiles von ips könnten hilfreich sein. Dort sollten die letztens Zeilen Aufschluss geben wo ips hängt.

paresy

ckerndl · 16. Juli 2010 um 19:25

ich poste die letzten zeilen wenn er mal wieder hängt dies macht er ja nur alle paar Wochen.

ckerndl · 25. Juli 2010 um 00:59

so nun war es mal wieder so weit diesmal sogar beim live Update
hier die letzten Zeilen der Log Datei

5.07.2010 00:54:06.781 | 55428 | DEBUG   | ExecuteThreadID #2   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 6780 ms
25.07.2010 00:54:15.656 |     0 | WARNING | Kernel               | Service Shutdown requested!
25.07.2010 00:54:15.656 |     0 | MESSAGE | Kernel               | *** IPS HERUNTERFAHREN
25.07.2010 00:54:15.671 | 52557 | DEBUG   | ExecuteThreadID #4   | Skriptausführung: 52557.ips.php ~ Absender: Shutdown
25.07.2010 00:54:15.671 | 21345 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K11 Steckdose WZ Rechts] = False
25.07.2010 00:54:15.671 | 10121 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K12 Steckdose Eingang] = False
25.07.2010 00:54:15.671 | 20257 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K13 Deckenventilator] = False
25.07.2010 00:54:15.671 | 22487 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K14 Licht Küche Eltako] = False
25.07.2010 00:54:15.671 | 24597 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K15 Steckdose Küche] = False
25.07.2010 00:54:15.671 | 39222 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K16 Steckdose WZ Mitte] = False
25.07.2010 00:54:15.671 | 37410 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K17 Steckdose WZ Büro] = False
25.07.2010 00:54:15.671 | 13070 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K18 WZ Steckdose Monitor] = False
25.07.2010 00:54:15.671 | 15617 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K16 Steckdose WZ Links] = False
25.07.2010 00:54:15.671 | 23079 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K22 Licht Bar Eltako] = False
25.07.2010 00:54:15.671 | 39821 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K23 Licht Wohnzimmer Eltako] = False
25.07.2010 00:54:15.687 | 37168 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K24 Audioplay] = False
25.07.2010 00:54:15.687 | 22273 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K25 Schlafzimmer Heizkörper] = False
25.07.2010 00:54:15.687 | 43049 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K26 Wohnzimmer Heizkörper] = False
25.07.2010 00:54:15.687 | 23151 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K27 Küche Heizkörper] = False
25.07.2010 00:54:15.687 | 59021 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K28 Bad Heizkörper] = False
25.07.2010 00:54:15.687 | 37560 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte1\K1Do] = False
25.07.2010 00:54:15.750 | 11361 | DEBUG   | ExecuteThreadID #6   | Skriptausführung: 11361.ips.php ~ Absender: Ereignis #21951, Variable #37560, Auslöser: OnUpdate
25.07.2010 00:54:15.859 | 11361 | DEBUG   | ExecuteThreadID #6   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 109 ms
25.07.2010 00:54:15.937 | 51871 | DEBUG   | VariableManager      | [.System\Aktoren\RS232\Karte2\K2Do] = False
25.07.2010 00:54:15.937 | 59966 | DEBUG   | ExecuteThreadID #7   | Skriptausführung: 59966.ips.php ~ Absender: Ereignis #58607, Variable #51871, Auslöser: OnUpdate
25.07.2010 00:54:15.937 | 52557 | DEBUG   | ExecuteThreadID #4   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 273 ms
25.07.2010 00:54:15.937 | 15429 | MESSAGE | Event Control        | Herunterfahr-Skript ausgeführt
25.07.2010 00:54:16.46 | 59966 | DEBUG   | ExecuteThreadID #7   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 109 ms
25.07.2010 00:54:16.62 |     0 | MESSAGE | Kernel               | Deinitialisiere...
25.07.2010 00:54:16.62 |     0 | MESSAGE | EventManager         | Warte auf Terminierung des Ereignisthread...
25.07.2010 00:54:16.171 |     0 | MESSAGE | TimerPool            | Warte auf Terminierung des Timerthreads... Name: UpdateEvent, Location: [.System\Sensoren\Schlafzimmer Status\Klein geöffnet]

es schaut wohl so aus wenn noch Script läuft da er dieses nicht beenden kann und dann hängt.

paresy · 25. Juli 2010 um 01:05

Was ist das für eine Instanz?
System\Sensoren\Schlafzimmer Status\Klein geöffnet

paresy

ckerndl · 25. Juli 2010 um 11:39

Das ist ein One Wire DS2405 als Digitaler Eingang. Gesetzt auf 10 Sekunden Intervall.

paresy · 25. Juli 2010 um 11:55

Hmmm… Ok… Kannst du vielleicht noch gucken, wann die letzte Aktualisierung vor dem Herunterfahren gewesen ist? (Einfach am Ende nach einer Variable der Instanz zu suchen)

Die Frage ist halt, ob die Instanz schon vorher „blockiert“ war, oder erst zum Shutdown Zeitpunkt blockiert.

paresy

ckerndl · 25. Juli 2010 um 12:07

24.07.2010 23:39:57.468 | 55503 | WARNING | TimerID #18, TimerThread #3 | [.System\Sensoren\Schlafzimmer Status\Klein gekippt] = Zeitüberschreitung der Anfrage

mal das Logfile vorher:

24.07.2010 23:35:36.00 | 45397 | DEBUG   | ExecuteThreadID #9   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 504 ms
24.07.2010 23:35:36.515 | 36519 | DEBUG   | VariableManager      | [.System\Sensoren\Temperatur\Gefrierschrank\Temperatur] = -15,75
24.07.2010 23:35:37.62 | 54062 | DEBUG   | VariableManager      | [.System\Sensoren\Schlafzimmer Status\Klein geöffnet\Status] = True
24.07.2010 23:35:37.187 | 49736 | DEBUG   | VariableManager      | [.System\Sensoren\Schlafzimmer Status\Klein gekippt\Status] = True
24.07.2010 23:35:37.312 | 26723 | DEBUG   | VariableManager      | [.System\Sensoren\Schlafzimmer Status\Leer\Status] = True
24.07.2010 23:35:37.437 | 29238 | DEBUG   | VariableManager      | [.System\Sensoren\Schlafzimmer Status\Gross geöffnet\Status] = False
24.07.2010 23:35:37.562 | 46593 | DEBUG   | VariableManager      | [.System\Sensoren\Schlafzimmer Status\Deckenfenster\Status] = True
24.07.2010 23:35:41.156 | 34249 | DEBUG   | VariableManager      | [.System\Sensoren\Temperatur\Serverraum\Temperatur] = 29,6875
24.07.2010 23:35:41.812 | 38416 | DEBUG   | VariableManager      | [.System\Sensoren\Stromstatus\1-WireInput Modul\L1 / L2\Licht Wohnzimmer] = False
24.07.2010 23:35:41.812 | 14983 | DEBUG   | VariableManager      | [.System\Sensoren\Stromstatus\1-WireInput Modul\L1 / L2\Licht Bar] = False
24.07.2010 23:35:41.921 | 48873 | DEBUG   | VariableManager      | [.System\Sensoren\Stromstatus\1-WireInput Modul\L3 / L4\Licht Küche] = False
24.07.2010 23:35:41.921 | 35250 | DEBUG   | VariableManager      | [.System\Sensoren\Stromstatus\1-WireInput Modul\L3 / L4\Leer] = False
24.07.2010 23:35:42.718 | 45099 | DEBUG   | VariableManager      | [.System\Sensoren\Wetter\Regensensor\Regenstatus] = False
24.07.2010 23:35:42.718 | 14966 | DEBUG   | VariableManager      | [.System\Sensoren\Wetter\Regensensor\Unbenützt] = False
24.07.2010 23:35:57.00 | 19202 | DEBUG   | ExecuteThreadID #5   | Skriptausführung: 19202.ips.php ~ Absender: Ereignis #16660, Zeit Ereignis
24.07.2010 23:35:57.15 | 19202 | DEBUG   | ExecuteThreadID #5   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 5 ms
24.07.2010 23:35:57.187 | 55503 | WARNING | TimerID #18, TimerThread #8 | [.System\Sensoren\Schlafzimmer Status\Klein gekippt] = Zeitüberschreitung der Anfrage
24.07.2010 23:35:57.312 | 59705 | WARNING | TimerID #19, TimerThread #9 | [.System\Sensoren\Schlafzimmer Status\Leer] = Zeitüberschreitung der Anfrage
24.07.2010 23:35:57.437 | 55297 | WARNING | TimerID #17, TimerThread #4 | [.System\Sensoren\Schlafzimmer Status\Gross geöffnet] = Zeitüberschreitung der Anfrage
24.07.2010 23:35:58.62 | 17533 | WARNING | TimerID #20, TimerThread #6 | [.System\Sensoren\Schlafzimmer Status\Deckenfenster] = Zeitüberschreitung der Anfrage
24.07.2010 23:36:00.00 | 55428 | DEBUG   | ExecuteThreadID #1   | Skriptausführung: 55428.ips.php ~ Absender: Ereignis #44658, Zeit Ereignis
24.07.2010 23:36:00.15 | 17815 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Filler] = True
24.07.2010 23:36:00.125 | 19101 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Verlag] = True
24.07.2010 23:36:00.187 | 19349 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Bigbackup] = True
24.07.2010 23:36:00.218 | 20354 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\IPS Kellerer] = True
24.07.2010 23:36:00.265 | 20760 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Network SW] = True
24.07.2010 23:36:00.296 | 27068 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\VPN Matthias] = True
24.07.2010 23:36:00.359 | 29482 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\VPN Büro SÜD] = True
24.07.2010 23:36:00.359 | 30149 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Fritzbox] = True
24.07.2010 23:36:00.484 | 31326 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Orthogoll] = True
24.07.2010 23:36:00.500 | 42396 | DEBUG   | ExecuteThreadID #5   | Skriptausführung: 42396.ips.php ~ Absender: Ereignis #54669, Zeit Ereignis
24.07.2010 23:36:00.500 | 36027 | MESSAGE | VariableManager      | [.System\Variablen\Systemvariablen\Time\current_time] = 24.07.10 23:36
24.07.2010 23:36:00.500 | 42396 | DEBUG   | ExecuteThreadID #5   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 4 ms
24.07.2010 23:36:00.531 | 33652 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\www.fkc-online.de] = True
24.07.2010 23:36:00.593 | 39952 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\www.kerndl.info] = True
24.07.2010 23:36:01.718 | 40189 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Homeserver] = False
24.07.2010 23:36:03.968 | 41271 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Dreambox] = False
24.07.2010 23:36:04.15 | 49769 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Webserver1] = True
24.07.2010 23:36:04.62 | 49881 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Internet] = True
24.07.2010 23:36:04.171 | 52616 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Orthogollhome] = True
24.07.2010 23:36:04.218 | 57525 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\Newsletter] = True
24.07.2010 23:36:04.265 | 59261 | DEBUG   | VariableManager      | [.System\Netzwerk\Netzwerkstatus\www.bsh-group.com] = True
24.07.2010 23:36:04.265 | 55428 | DEBUG   | ExecuteThreadID #1   | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 4259 ms
24.07.2010 23:36:06.62 | 56317 | WARNING | TimerID #10, TimerThread #2 | [.System\Sensoren\Wohnzimmer Status\DS2408] = Zeitüberschreitung der Anfrage

So wie es aussieht als hing der 1-Wire Bus ab 24.07.2010 23:35:57.
Treiber ist der 4.00

Ich hatte schon mal gefühl wenn etwas mit dem 1-wire Bus nicht stimmt das IPS sich „verschluckt“. Ist wohl auch so oder ?

ckerndl · 25. Juli 2010 um 13:06

Ich hab nun nochmal in den alten Logs nachgelesen und dort war es wohl auch überall so. Vielleicht fällt das bei mir stark auf weil ich den Rechner eben einmal nachts rebooten lasse und selbst bei mir kommt das nur ca alle 2 Wochen vor.

ckerndl · 27. Juli 2010 um 17:17

Any News?