Lights Out - Dienstproblem

[ahgloom]

Hallo,

ich hab ein nicht kleines Problem mit Lights Out, nichts dramatisches, wollte aber mal nachfragen ob das überhaupt durch Lights Out verursacht wird oder Windows daran Schuld ist.

Ich nutze auf meinem Home Server virtuelle Maschinen. Das ganze hab ich jetzt mit Virtual Server 2005 von Microsoft aufgezogen, welches sich ja als Dienst installiert. Jetzt will ich zusätzlich noch Lights Out benutzen. Der Server soll sich Abends in den Standby versetzten und Morgens wieder hochfahren. Das funktioniert auch alles wunderbar. Zusätzlich habe ich eingestellt, dass der Virtual Server Dienst dabei beendet und wieder gestartet wird (über die Einstellungen des Plugins).
Beim Beenden des Virtual Server Dienstes fahren sich alle Gastbetriebsystem automatisch herunter. Und da ist das Problem. Wenn nämlich der Server jetzt in den StandBy Modus geht passiert es manchmal, dass der Virtual Server Dienst noch nicht richtig beendet ist (herausgefunden über Ereignisanzeige). Fährt sich dann der Server morgens hoch, kann der Dienst nicht gestartet werden, weil er immer noch am Beenden festhängt. Es sieht also so aus, als ob die Wartezeit für die Beendigung der Dienste zu kurz ist.

Überprüft Lights Out das irgendwie, ob der Dienst tatsächlich beendet wurde? Oder gibt er nur einfach das Kommando und Windows kommt einfach nicht nach?

Natürlich kann ich das Problem umgehen, indem ich den Dienst manuell beende und starte. Anders wäre das aber schon schöner.

Danke für die Antworten im Voraus!

ahgloom

[Martin]

Hi,

kannst du mir mal bitte ein LightsOutService.xxx.log schicken bei dem der Fehler auftrat?
Liegt unter C:\Dokumente und Einstellungen\All Users\Anwendungsdaten\LightsOut

Gruß
Martin

[ahgloom]

Hallo Martin,

hier meine Log-Datei:

Code: Alles auswählen

2009-02-12 00:00:10.1875 [5] ----------------------------
2009-02-12 00:00:10.2343 [5] LightsOutService    : 0.7.7.122
2009-02-12 00:00:10.2343 [5] Aktion              : DoNothing
2009-02-12 00:00:10.2343 [5] AlwaysOnDuringBackup: True
2009-02-12 00:00:10.2343 [5] BackupStartTime     : 09:00
2009-02-12 00:00:10.2343 [5] BackupEndTime       : 17:00
2009-02-12 00:00:10.2343 [5] ExcludedClients     : 
2009-02-12 00:00:10.2343 [5] IsModified          : False
2009-02-12 00:00:10.2343 [5] DaysToShow          : 7
2009-02-12 00:00:10.2343 [5] ShowBackup          : True
2009-02-12 00:00:10.2343 [5] ShowServer          : False
2009-02-12 00:00:10.2343 [5] MonitorRemoteAccess : False
2009-02-12 00:00:10.2343 [5] StartStopServices   : DriveExtenderMigrator,Virtual Server
2009-02-12 00:00:10.2343 [5] WaitTime            : 10
2009-02-12 00:00:10.2343 [5] ----------------------------
2009-02-12 00:00:10.2343 [5] Client              : PCMG2
2009-02-12 00:00:10.2343 [5] Client              : HTPCMG
2009-02-12 00:00:10.2343 [5] Client              : PCHUGO
2009-02-12 00:00:10.2343 [5] Client              : LAPTOPASUS
2009-02-12 00:00:10.2343 [5] Client              : SERVERVM
2009-02-12 00:00:10.2343 [5] Client              : LAPTOPMG
2009-02-12 00:00:10.2343 [5] Client              : PC-MONIKA
2009-02-12 00:00:10.2343 [5] Clients found       : 7(7)
2009-02-12 00:00:10.2343 [5] ----------------------------
2009-02-12 02:00:10.8125 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=Suspend/DoNothing
2009-02-12 02:00:10.8437 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=Suspend/DoNothing
2009-02-12 02:00:40.8437 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 02:00:40.8437 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 02:00:40.8437 [5]   CheckAction: a=Suspend/DoNothing force=False IsInStandby=False
2009-02-12 02:00:40.8906 [5]   >>> Suspend <<<
2009-02-12 02:00:40.9375 [5] + HandleServices: DriveExtenderMigrator Status=Running
2009-02-12 02:00:40.9375 [5]  < stopping DriveExtenderMigrator
2009-02-12 02:00:42.0937 [5]  < service DriveExtenderMigrator Status=StopPending
2009-02-12 02:00:43.0937 [5]  < service DriveExtenderMigrator Status=Stopped
2009-02-12 02:00:43.0937 [5] + HandleServices: Virtual Server Status=Running
2009-02-12 02:00:43.0937 [5]  < stopping Virtual Server
2009-02-12 02:00:44.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:45.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:46.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:47.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:48.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:49.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:50.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:51.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:52.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:53.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 02:00:54.1562 [5]  < service Virtual Server Status=StopPending
2009-02-12 08:00:16.1250 [4] Power event: QuerySuspend
2009-02-12 08:00:16.1875 [3] Power event: Suspend
2009-02-12 08:00:16.4687 [3] Power event: ResumeAutomatic
2009-02-12 08:00:16.4687 [3] + HandleServices: DriveExtenderMigrator Status=Stopped
2009-02-12 08:00:16.8125 [3]  > starting DriveExtenderMigrator
2009-02-12 08:00:25.6562 [3] + HandleServices: Virtual Server Status=StopPending
2009-02-12 09:00:01.1875 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 09:00:01.1875 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 11:10:58.2187 [7] Backup state changed to: WHS_BACKUP_BACKUP
2009-02-12 11:11:01.1875 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 11:11:01.1875 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 11:12:47.6406 [7] Backup state changed to: WHS_BACKUP_IDLE
2009-02-12 11:13:01.2031 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 11:13:01.2031 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 17:00:02.6875 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-12 17:00:02.8125 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing

Sieht so aus, also ob der Service immer im StopPending-Status bleibt.
Hier mal der Auszug an einem "funktionierendem" Tag, da wird der Virtual Server Service anscheinend beendet:

Code: Alles auswählen

2009-02-10 00:00:04.3281 [5] ----------------------------
2009-02-10 00:00:04.4218 [5] LightsOutService    : 0.7.7.122
2009-02-10 00:00:04.4218 [5] Aktion              : DoNothing
2009-02-10 00:00:04.4218 [5] AlwaysOnDuringBackup: True
2009-02-10 00:00:04.4218 [5] BackupStartTime     : 09:00
2009-02-10 00:00:04.4218 [5] BackupEndTime       : 17:00
2009-02-10 00:00:04.4218 [5] ExcludedClients     : 
2009-02-10 00:00:04.4218 [5] IsModified          : False
2009-02-10 00:00:04.4218 [5] DaysToShow          : 7
2009-02-10 00:00:04.4218 [5] ShowBackup          : True
2009-02-10 00:00:04.4218 [5] ShowServer          : False
2009-02-10 00:00:04.4218 [5] MonitorRemoteAccess : False
2009-02-10 00:00:04.4218 [5] StartStopServices   : DriveExtenderMigrator,Virtual Server
2009-02-10 00:00:04.4375 [5] WaitTime            : 10
2009-02-10 00:00:04.4375 [5] ----------------------------
2009-02-10 00:00:04.4375 [5] Client              : PCMG2
2009-02-10 00:00:04.4375 [5] Client              : HTPCMG
2009-02-10 00:00:04.4375 [5] Client              : PCHUGO
2009-02-10 00:00:04.4375 [5] Client              : LAPTOPASUS
2009-02-10 00:00:04.4375 [5] Client              : SERVERVM
2009-02-10 00:00:04.4375 [5] Client              : LAPTOPMG
2009-02-10 00:00:04.4375 [5] Client              : PC-MONIKA
2009-02-10 00:00:04.4375 [5] Clients found       : 7(7)
2009-02-10 00:00:04.4375 [5] ----------------------------
2009-02-10 02:00:04.9218 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=Suspend/DoNothing
2009-02-10 02:00:04.9218 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=Suspend/DoNothing
2009-02-10 02:00:34.9375 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 02:00:34.9375 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 02:00:34.9375 [5]   CheckAction: a=Suspend/DoNothing force=False IsInStandby=False
2009-02-10 02:00:34.9375 [5]   >>> Suspend <<<
2009-02-10 02:00:35.0937 [5] + HandleServices: DriveExtenderMigrator Status=Running
2009-02-10 02:00:35.0937 [5]  < stopping DriveExtenderMigrator
2009-02-10 02:00:36.2968 [5]  < service DriveExtenderMigrator Status=StopPending
2009-02-10 02:00:37.2968 [5]  < service DriveExtenderMigrator Status=Stopped
2009-02-10 02:00:37.2968 [5] + HandleServices: Virtual Server Status=Running
2009-02-10 02:00:37.2968 [5]  < stopping Virtual Server
2009-02-10 02:00:38.3593 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:39.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:40.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:41.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:42.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:43.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:44.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:45.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:46.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:47.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 02:00:48.3906 [5]  < service Virtual Server Status=StopPending
2009-02-10 08:00:14.9218 [3] Power event: QuerySuspend
2009-02-10 08:00:14.9375 [4] Power event: Suspend
2009-02-10 08:00:15.3906 [3] Power event: ResumeAutomatic
2009-02-10 08:00:15.4062 [3] + HandleServices: DriveExtenderMigrator Status=Stopped
2009-02-10 08:00:15.4375 [3]  > starting DriveExtenderMigrator
2009-02-10 08:00:27.2500 [3] + HandleServices: Virtual Server Status=Stopped
2009-02-10 08:00:27.2500 [3]  > starting Virtual Server
2009-02-10 08:00:29.9062 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 08:00:29.9062 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 08:00:44.9218 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=True, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 08:00:44.9218 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 09:00:00.2968 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 09:00:00.3593 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 10:44:18.5937 [7] Backup state changed to: WHS_BACKUP_BACKUP
2009-02-10 10:44:30.8125 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 10:44:30.8125 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 10:46:01.7500 [7] Backup state changed to: WHS_BACKUP_IDLE
2009-02-10 10:46:15.8125 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 10:46:15.8125 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 11:28:39.0468 [7] Backup state changed to: WHS_BACKUP_BACKUP
2009-02-10 11:28:46.0156 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 11:28:46.0156 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 11:35:54.8437 [7] Backup state changed to: WHS_BACKUP_IDLE
2009-02-10 11:36:01.0468 [5]  ClientActive=False, BackupRunning=True, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 11:36:01.0468 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 17:00:02.6093 [5]  ClientActive=False, BackupRunning=False, InBackupTime=True, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing
2009-02-10 17:00:02.6562 [5]  ClientActive=False, BackupRunning=False, InBackupTime=False, InTimeSpan=False, RemoteAccess=False, Action=DoNothing/DoNothing

ahgloom