Seit 2 Tagen bleibt mein WHS wach Trots LightOut

[m3Sa]

Hallo Leute,

ich benutze LightsOut schon seit einigen Wochen, doch die letzten Tage lief er einfach weiter

Hier einen auszug der Log wo er noch in StandBy ging vom 24.06:

Code: Alles auswählen

2008-06-24 22:00:02.9687 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-06-24 22:00:02.9687 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-24 22:00:02.9687 [5]   CheckAction: a=Suspend/Suspend force=False IsInStandby=False
2008-06-24 22:00:02.9687 [5]   >>> Suspend <<<
2008-06-24 22:00:02.9843 [5] + HandleServices: DriveExtenderMigrator Status=Running
2008-06-24 22:00:02.9843 [5]  < stopping DriveExtenderMigrator
2008-06-24 22:00:04.0000 [5]  < service DriveExtenderMigrator Status=StopPending
2008-06-24 22:00:05.0000 [5]  < service DriveExtenderMigrator Status=Stopped

und am Tag danach 25.06:

Code: Alles auswählen

2008-06-25 22:00:30.8437 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-25 22:00:30.8593 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-25 22:15:15.8593 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-25 22:15:15.8593 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-25 22:15:15.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:15:30.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:15:45.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:16:00.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:16:15.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:16:30.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:16:45.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:17:00.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:17:15.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:17:30.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:17:45.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:18:00.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:18:15.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:18:30.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-25 22:18:45.8593 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
........

die ganze Nacht die Meldung weiter...
Dann kommt Backuptime...

Code: Alles auswählen

2008-06-26 00:59:26.2656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 00:59:41.2656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 00:59:56.2656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 01:00:11.2656 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-26 01:00:11.2656 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-06-26 02:00:11.2656 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-06-26 02:00:11.2656 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-06-26 03:00:12.4843 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-06-26 03:00:12.5000 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-06-26 03:00:12.5000 [5]   CheckAction: a=Suspend/Suspend force=True IsInStandby=True
2008-06-26 03:00:27.5000 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 03:00:42.5000 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 03:00:57.5000 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-06-26 03:01:12.5156 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True

und wieder weiter...

Wüsste nicht was ich an dem Vortag geändert habe, dass nichts mehr funst

Bin für jegliche Hilfe dankbar!

MfG
Carsten

[Martin]

Mal bitte den Dienst LightsOut auf dem Server stoppen und dann neu starten.
Aus irgend einem Grund schlug der erste Standby fehl, LightsOut ist aber der Meinung dass der Standby noch andauert.

Gruß
Martin

[m3Sa]

Hey danke,

hab ich gleich mal gemacht
Hätt ich auch selber drauf kommen können *gg*

Werd mich meld wenns problem noch weiter besteht!

MfG
Carsten

[m3Sa]

Hey,

also ab und zu hängt sich der Dienst einfach auf!

Liegt es evtl daran, dass mein Server nie hoch und runter fährt?
Er geht bei mir nur innen StandBy.

MfG
Carstem

[Martin]

Wie äußert sich das Aufhängen? Ist der Dienst gestorben und kann neu gestartet werden?
- Was steht im Eventlog?
- Was steht im Logfile?

Meinen Server fahre ich mit LightsOut immer in den Standby. Ein Problem gab es da bisher nicht.

Gruß
Martin

[m3Sa]

Hey,

also die Logfiles werden wie beim 1. Beitrag weiter geschrieben, eben nur dass er nicht in StandBy geht.
Sobald ich den Dienst beende und wieder starte ist wieder alles i.O.

Hier die Auszüge vom Tag des Geschehens...

LightsOutService.2008-08-18.log:

Code: Alles auswählen

2008-08-18 01:00:11.5625 [3] Power event: QuerySuspend
2008-08-18 01:00:11.9375 [4] Power event: Suspend
2008-08-18 01:00:12.3125 [8] Power event: ResumeAutomatic
2008-08-18 01:00:12.3125 [8] + HandleServices: DriveExtenderMigrator Status=Stopped
2008-08-18 01:00:12.3125 [8]  > starting DriveExtenderMigrator
2008-08-18 01:00:26.5625 [5] ----------------------------
2008-08-18 01:00:26.5625 [5] LightsOutService    : 0.7.6.101
2008-08-18 01:00:26.5625 [5] Aktion              : Suspend
2008-08-18 01:00:26.5625 [5] AlwaysOnDuringBackup: True
2008-08-18 01:00:26.5625 [5] BackupStartTime     : 01:00
2008-08-18 01:00:26.5625 [5] BackupEndTime       : 02:00
2008-08-18 01:00:26.5625 [5] ExcludedClients     : 
2008-08-18 01:00:26.5625 [5] IsModified          : False
2008-08-18 01:00:26.5625 [5] DaysToShow          : 14
2008-08-18 01:00:26.5625 [5] ShowBackup          : False
2008-08-18 01:00:26.5625 [5] ShowServer          : True
2008-08-18 01:00:26.5625 [5] StartStopServices   : DriveExtenderMigrator
2008-08-18 01:00:26.5625 [5] WaitTime            : 15
2008-08-18 01:00:26.5625 [5] ----------------------------
2008-08-18 01:00:26.5625 [5] Client              : CARSTEN
2008-08-18 01:00:26.5625 [5] Client              : KLAUS
2008-08-18 01:00:26.5625 [5] Clients found       : 2(2)
2008-08-18 01:00:26.5625 [5] ----------------------------
2008-08-18 01:00:26.5781 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 01:00:26.5781 [5]  ClientActive=True, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 01:15:26.5781 [5]  ClientActive=True, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 01:15:26.5781 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:00:11.5781 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:00:11.5781 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:00:11.5781 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=False
2008-08-18 02:00:11.5781 [5]   >>> Suspend <<<
2008-08-18 02:00:11.5781 [5] + HandleServices: DriveExtenderMigrator Status=Running
2008-08-18 02:00:11.5781 [5]  < stopping DriveExtenderMigrator
2008-08-18 02:00:12.5781 [5]  < service DriveExtenderMigrator Status=StopPending
2008-08-18 02:00:13.5781 [5]  < service DriveExtenderMigrator Status=Stopped
2008-08-18 02:00:13.5937 [4] Power event: QuerySuspend
2008-08-18 02:00:28.5937 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:00:28.5937 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:15:13.5937 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:15:13.5937 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 02:15:13.5937 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:15:28.5937 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:15:43.5937 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:15:58.5937 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:16:13.6093 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:16:28.6093 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:16:43.6093 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:16:58.6093 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 02:17:13.6093 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True

...

2008-08-18 09:59:15.3750 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 09:59:30.3906 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 09:59:45.3906 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 10:00:00.3906 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 10:00:00.3906 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-08-18 15:23:30.4531 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-08-18 15:23:30.4531 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-08-18 22:00:00.5312 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=True, Action=Suspend/Suspend
2008-08-18 22:00:00.5312 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 23:02:15.5468 [5]  ClientActive=True, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 23:02:15.5468 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend
2008-08-18 23:02:15.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:02:30.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:02:45.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:03:00.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:03:15.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:03:30.5468 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True

...

2008-08-18 23:58:45.7656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:59:00.7656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:59:15.7656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:59:30.7656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True
2008-08-18 23:59:45.7656 [5]   CheckAction: a=DoNothing/Suspend force=False IsInStandby=True

Nach der Backup Time ging er nicht mehr in StandBy!


LightsOut.2008-08-18.log:

Code: Alles auswählen

2008-08-18 01:00:01.5156 LightsOut [1] timer1_Tick
2008-08-18 01:00:11.9375 LightsOut [1] tbbRefresh_Click
2008-08-18 01:00:12.0156 LightsOut [1] AddData: found 2 clients
2008-08-18 01:00:12.3125 LightsOut [1] AddData: processed 2 clients
2008-08-18 01:00:12.3281 LightsOut [1] AddData: processed server and backup
2008-08-18 01:02:01.5000 LightsOut [1] timer1_Tick
2008-08-18 01:02:01.5000 LightsOut [1] tbbRefresh_Click
2008-08-18 01:02:01.5000 LightsOut [1] AddData: found 2 clients
2008-08-18 01:02:01.5156 LightsOut [1] AddData: processed 2 clients
2008-08-18 01:02:01.5156 LightsOut [1] AddData: processed server and backup
2008-08-18 01:04:01.5000 LightsOut [1] timer1_Tick
2008-08-18 01:04:01.5000 LightsOut [1] tbbRefresh_Click
2008-08-18 01:04:01.5000 LightsOut [1] AddData: found 2 clients
2008-08-18 01:04:01.5000 LightsOut [1] AddData: processed 2 clients
2008-08-18 01:04:01.5156 LightsOut [1] AddData: processed server and backup

...

2008-08-18 23:54:01.5000 LightsOut [1] timer1_Tick
2008-08-18 23:54:01.5000 LightsOut [1] tbbRefresh_Click
2008-08-18 23:54:01.5000 LightsOut [1] AddData: found 2 clients
2008-08-18 23:54:01.5000 LightsOut [1] AddData: processed 2 clients
2008-08-18 23:54:01.5156 LightsOut [1] AddData: processed server and backup
2008-08-18 23:56:01.5000 LightsOut [1] timer1_Tick
2008-08-18 23:56:01.5000 LightsOut [1] tbbRefresh_Click
2008-08-18 23:56:01.5000 LightsOut [1] AddData: found 2 clients
2008-08-18 23:56:01.5000 LightsOut [1] AddData: processed 2 clients
2008-08-18 23:56:01.5000 LightsOut [1] AddData: processed server and backup
2008-08-18 23:58:01.5000 LightsOut [1] timer1_Tick
2008-08-18 23:58:01.5000 LightsOut [1] tbbRefresh_Click
2008-08-18 23:58:01.5000 LightsOut [1] AddData: found 2 clients
2008-08-18 23:58:01.5000 LightsOut [1] AddData: processed 2 clients
2008-08-18 23:58:01.5156 LightsOut [1] AddData: processed server and backup

Das und nichts anderes wurde den ganzen Tag hineingeschreiben...

MfG
Carsten

[Martin]

Das Problem scheint mit hier zu liegen:
2008-08-18 02:00:13.5781 [5] < service DriveExtenderMigrator Status=Stopped
2008-08-18 02:00:13.5937 [4] Power event: QuerySuspend
2008-08-18 02:00:28.5937 [5] ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, Action=Suspend/Suspend

Nach dem QuerySuspend scheint eine andere Anwendung/Dienst den Standby zu verhindern, LightsOut denkt aber dass der Standby aktiviert wurde.
(Es fehlt der PowerEvent: Suspend Eintrag im Log).
Warum das bei dir passiert kann ich so schlecht erkennen.

Welche anderen Dienste/Add-Ins sind denn aktiv?
Wenn das Problem aufgetreten ist, kannst du dann den Server händisch in den Standby fahren?
Und steht etwas im Ereignisprotokoll?

Gruß
Martin

[m3Sa]

Hab nen bissel was installiert ja,
hoffe ich find alles *g*

Dienste:
- VNC Server
- VLC WebServer
- Virtual Server
- Ventrilo Server
- uTorrent
- Verschiedene SQL Dienste
- O&O Defrag
- Hamachi

WHS Console:
- uTorrent
- Disk Management
- Event Viewer
- AutoExit
- FRITZ!Box Anrufliste

Letzter Eintrag von LightsOut in der AnwendungLog ->

Zwischenablage05.jpg (34.14 KiB) 3079 mal betrachtet

Auffälliger Eintrag in der Systemlog ->

Zwischenablage06.jpg (51.15 KiB) 3079 mal betrachtet

Hoffe ich kann so weiterhelfen!

Hab noch nicht versucht Manuell in StandBy zu fahren da es nach Neustart des Dienstes wieder funktioniert hat

MfG
Carsten

[Martin]

Eventuell verhindert Virtual Server oder einer der anderen Server (VNC, VLC WebServer, Ventrilo Server)den Standby.

Kannst du das mal testen? Zuerst ob der manuelle Standby funktioniert, dann immer einen deaktivieren.

Gruß
Martin

[m3Sa]

Ok,

muss nun erstmal warten bis der Fehler wieder auftritt...

Meld mich dann!

MfG
Carsten