ich fass es nicht: verwendet Ihr Voodo-Zauber?
Heute morgen haben mich meine IPS-gesteuerten Sonossis nicht geweckt. Fehlersuche ergab ein offensichtlich gleiches Fehlerbild wie oben beschrieben:
IPS-RAM Auslastung ab 01:50 Uhr von 130MB auf 170MB
IPS-CPU-Auslastung 40%(normal sind unter 10%)
IPS war ab 1:50 Uhr absolut tot, keine DB-Daten mehr geschrieben, keine Scripte ausgeführt, nix. Task musste abgeschossen werden.
dann hab ich noch das im Logfile gefunden (logfile wurde auch nach 1:50 Uhr fortgeschrieben)
11.11.2011 01:50:20.005 | 0 | DEBUG | ExecuteThreadID #36 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 9056 ms
11.11.2011 01:50:20.145 | 0 | DEBUG | ExecuteThreadID #36 | Skriptausführung: ips.php ~ Absender: WebInterface
11.11.2011 01:50:20.505 | 53372 | DEBUG | ExecuteThreadID #5 | Skriptausführung: wecker.ips.php ~ Absender: Ereignis #28811, Zeit Ereignis
11.11.2011 01:50:20.552 | 53372 | DEBUG | ExecuteThreadID #5 | Ausgeführt, Resultat: 1, Erfolgreich: True, Zeit: 48 ms
11.11.2011 01:50:20.770 | 54659 | MESSAGE | Client Socket | Socket: Getrennt
11.11.2011 01:50:20.770 | 56788 | DEBUG | ExecuteThreadID #5 | Skriptausführung: 56788.ips.php ~ Absender: StatusEvent
11.11.2011 01:50:20.802 | 56788 | DEBUG | ExecuteThreadID #8 | Skriptausführung: 56788.ips.php ~ Absender: StatusEvent
11.11.2011 01:50:24.005 | 53372 | DEBUG | ExecuteThreadID #24 | Skriptausführung: wecker.ips.php ~ Absender: Ereignis #28811, Zeit Ereignis
11.11.2011 01:50:26.755 | 47543 | DEBUG | VariableManager | [Haussteuerung\Wohnzimmer\HZ Solltemperatur\ADJUSTING_COMMAND] = 0
11.11.2011 01:50:30.771 | 54659 | MESSAGE | Client Socket | Socket: Verbinde.... #1
11.11.2011 01:50:33.552 | 30806 | WARNING | TimerID #12, TimerThread #8 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #03 Licht_Stom mitte] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:50:38.553 | 40444 | WARNING | TimerID #13, TimerThread #40 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #04 Licht_Strom unten] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:50:40.193 | 0 | ERROR | ScriptEngine | FunctionName: ips_getsessionmessages, ThreadID: 8176, CrashReport: date/time : 2011-11-11, 01:50:40, 147ms
computer name : R2D2
user name : SYSTEM <admin>
registered owner : Windows-Benutzer
operating system : Windows 2008 R2 x64 Service Pack 1 build 7601
system language : German
system up time : 4 days 6 hours
program up time : 10 hours 53 minutes
processors : 4x Intel(R) Xeon(R) CPU E5420 @ 2.50GHz
physical memory : 3918/8191 MB (free/total)
free disk space : (C:) 25,51 GB
display mode : 1024x768, 32 bit
process id : $aec
allocated memory : 126,01 MB
executable : ips.exe
exec. date/time : 2011-11-09 16:15
version : 2.5.0.0
compiled with : Delphi 2006/07
madExcept version : 3.0l
callstack crc : $01744d29, $e9856ee8, $e9856ee8
exception number : 4
exception class : EAccessViolation
exception message : Access violation at address 00402111 in module 'ips.exe'. Write of address 00000000.
thread $1ff0 (TExecuteThread):
00402111 +081 ips.exe System 220 +0 SysFreeMem
0040323c +004 ips.exe System 220 +0 @FreeMem
004077f7 +033 ips.exe System 220 +0 @DynArrayClear
00407510 +000 ips.exe System 220 +0 DynArrayClear
00407547 +02f ips.exe System 220 +0 DynArraySetLength
004076a9 +005 ips.exe System 220 +0 @DynArraySetLength
00537f31 +115 ips.exe InvokeRegistry 3284 +28 TDataContext.Destroy
00404ad4 +008 ips.exe System 220 +0 TObject.Free
00625d0b +7a3 ips.exe UIPSScriptEngine 1978 +129 TIPSScriptEngine.HandlePHPEvent
006220f6 +7d2 ips.exe UIPSScriptEngine 883 +127 TIPSScriptEngine.PHPLibraryFunctionsExecute
0061da94 +220 ips.exe PHPSapi 327 +47 DispatchRequest
64e3e45b +31b php5ts.dll execute
64e13078 +0f8 php5ts.dll zend_execute_scripts
64eea227 +247 php5ts.dll php_execute_script
0061a824 +01c ips.exe PHPApi 1461 +2 zend_hash_update
64ee9427 +217 php5ts.dll php_request_startup
0061e89b +1b3 ips.exe PHPSapi 837 +66 TPHPClient.RunScript
006275f7 +8fb ips.exe UIPSScriptEngine 2364 +112 TExecuteThread.Execute
00405a68 +028 ips.exe System 220 +0 ThreadWrapper
0044d755 +00d ips.exe madExcept CallThreadProcSafe
0044d7bf +037 ips.exe madExcept ThreadExceptFrame
76f33398 +010 kernel32.dll BaseThreadInitThunk
>> created by thread $1f64 (TServiceThread) at:
00626483 +083 ips.exe UIPSScriptEngine 2114 +12 TExecuteThread.Create
main thread ($1960):
00000000 +ffbdacc4 ips.exe madStackTrace +0 StackAddrToStr
>> stack will be calculated soon
cpu registers:
eax = 00000100
ebx = 00000000
ecx = 037c7908
edx = 037c23b0
esi = 00532d8c
edi = 00000000
eip = 00402111
esp = 06e4f714
ebp = 06e4f758
stack dump:
06e4f714 10 79 7c 03 42 32 40 00 - fc 77 40 00 d8 a6 3e 18 .y|.B2@..w@...>.
06e4f724 15 75 40 00 4c 75 40 00 - c0 a6 3e 18 00 00 00 00 .u@.Lu@...>.....
06e4f734 01 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f744 00 00 00 00 00 00 00 00 - 00 00 00 00 01 00 00 00 ................
06e4f754 d8 a6 3e 18 9c f7 e4 06 - ae 76 40 00 68 f7 e4 06 ..>......v@.h...
06e4f764 36 7f 53 00 00 00 00 00 - ac f7 e4 06 28 53 40 00 6.S.........(S@.
06e4f774 9c f7 e4 06 00 00 00 00 - 10 33 c2 03 01 00 00 00 .........3......
06e4f784 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f794 00 00 00 00 00 00 00 01 - 44 f8 e4 06 d7 4a 40 00 ........D....J@.
06e4f7a4 10 5d 62 00 24 5d 62 00 - 54 f8 e4 06 28 53 40 00 .]b.$]b.T...(S@.
06e4f7b4 44 f8 e4 06 00 00 00 00 - 78 1f 6e 07 01 00 00 00 D.......x.n.....
06e4f7c4 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f7d4 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f7e4 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f7f4 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f804 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f814 00 00 00 00 00 00 00 00 - c0 a6 3e 18 50 6c 59 03 ..........>.PlY.
06e4f824 b8 ae cc 02 00 01 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f834 00 00 00 00 00 00 00 00 - 00 00 00 00 00 00 00 00 ................
06e4f844 5c f9 e4 06 fb 20 62 00 - 40 f9 e4 06 00 00 00 00 \.....b.@.......
11.11.2011 01:50:43.553 | 50108 | WARNING | TimerID #14, TimerThread #1 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #01 Zentraleinspeisung] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:51:20.243 | 30009 | WARNING | TimerID #11, TimerThread #7 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #02 Licht_Strom oben] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:51:25.243 | 57896 | WARNING | TimerID #10, TimerThread #35 | [ProJet Gateway (IPS-868)] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:51:40.557 | 30806 | WARNING | TimerID #12, TimerThread #32 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #03 Licht_Stom mitte] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:51:46.557 | 40444 | WARNING | TimerID #13, TimerThread #34 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #04 Licht_Strom unten] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:51:51.557 | 50108 | WARNING | TimerID #14, TimerThread #14 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #01 Zentraleinspeisung] = Zeitüberschreitung beim Warten auf Antwort
11.11.2011 01:52:23.434 | 0 | DEBUG | ExecuteThreadID #35 | Skriptausführung: graph.php ~ Absender: WebInterface
11.11.2011 01:52:26.247 | 30009 | WARNING | TimerID #11, TimerThread #26 | [Smart Metering\IPS EKM-868\EKM-868 I Hausverteilung\EKM-868 #02 Licht_Strom oben] = Zeitüberschreitung beim Warten auf Antwort
Nachtrag II: die Variable 54659 im Logfile ist ein Client Socket, der Verbindung mit EventGhost auf einem Mediaportal-Rechner herstellen soll. dieser PC war zu dem Zeitpunkt aus. Normalerweise erkennt mein für diesen ClientSocket zuständiger Error-Handler den Zustand des PC’s und deaktiviert den CS solange der Mediaportal-PC offline ist. Sieht so aus als wäre der ClientSocket die Ursache für den Crash von IPS.