opsiclientd-Dienst startet nicht

Groo · Beitrag von **Groo** » 02 Aug 2010, 12:17

Hallo opsi-Community,

wir setzen seit letzter Woche opsi in der Firma auf ca 70 Rechnern ein. Heute morgen hatten wir den Effekt, dass der opsiclentd-Dienst auf 8 von 51 Rechnern beim ersten Hochfahren nicht gestartet ist.
(ähnlich wie hier beschrieben: viewtopic.php?f=6&t=1354&p=6817&hilit=+open+pipe+#p6817)

Opsiserver: opsi 3.4 Debian ServerVM
Preloginloader: 3.4.63

opsiclientd 3.99.0.0 bzw. prelogin.exe 1.1.2.13, opsigina.dll 1.2, pcptch.exe 3.9.3, winst 4.10.6.4

Opsi-winst: 4.10.7.4
Client-PCs: WinXP SP3

Als Symptom auf den betroffenen Rechnern wird beim Systemstart der blaue Windowsbildschirm mit Maus aber ohne Anmeldefenster für 2 - 14min angezeigt.
Das Windows Systemlog weist dabei folgende Fehler auf:

Quelle: Service Control Manager
EreignisID: 7009

Zeitüberschreitung (30000 ms) beim Verbindungsversuch mit Dienst opsiclientd.

Quelle: Service Control Manager
EreignisID: 7000

Der Dienst "opsiclientd" wurde aufgrund folgenden Fehlers nicht gestartet:
Der Dienst antwortete nicht rechtzeitig auf die Start- oder Steuerungsanforderung.

Der opsiclientd-Dienst lässt sich allerdings problemlos manuell starten. Ansonsten hat ein Neustart geholfen - der Dienst startetete dann auf 7 der 8 Rechner.

c:\tmp\opsi_loginblocker.txt von einem der betroffenen Rechner:

Code: Alles auswählen

2010-08-02 07:31:07 [6] Registry value LoginBlockerLogLevel read, result: 6
2010-08-02 07:31:07 [5] LoginBlockerLogLevel is: 6
2010-08-02 07:31:07 [6] Registry value LoginBlockerTimeoutConnect read, result: 120
2010-08-02 07:31:07 [5] LoginBlockerTimeoutConnect is: 120
2010-08-02 07:31:07 [6] Registry value LoginBlockerStart read, result: 1
2010-08-02 07:31:07 [5] LoginBlockerStart is: 1
2010-08-02 07:31:07 [6] Registry value OpsiServiceType read, result: 2
2010-08-02 07:31:07 [5] OpsiServiceType is: 2
2010-08-02 07:31:07 [6] Registry value NextGina read, result: msgina.dll
2010-08-02 07:31:07 [5] NextGina is: msgina.dll
2010-08-02 07:31:07 [5] Loading chained gina: msgina.dll
2010-08-02 07:31:16 [5] Communicating with service through pipe
2010-08-02 07:31:16 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:31:16 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:31:26 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:31:27 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:31:37 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:31:37 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:31:47 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:31:47 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:31:57 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:31:57 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:07 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:07 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:17 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:17 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:27 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:27 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:37 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:37 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:47 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:47 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:32:57 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:32:57 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:33:07 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:33:07 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:33:17 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:33:17 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:33:17 [3] Could not open pipe (timed out)
2010-08-02 07:33:17 [3] Service request '{"id": 1, "method": "getBlockLogin", "params": []}' failed
2010-08-02 07:33:17 [6] Last unblock time now set to: 1280727197
2010-08-02 07:33:17 [5] Not blocking!
2010-08-02 07:33:17 [5] WlxDisplaySASNotice: Not blocking
2010-08-02 07:36:12 [5] Communicating with service through pipe
2010-08-02 07:36:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:36:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:36:22 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:36:22 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:36:32 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:36:32 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:36:42 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:36:42 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:36:52 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:36:52 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:02 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:02 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:22 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:22 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:32 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:32 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:42 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:42 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:37:52 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:37:52 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:38:02 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:38:02 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:38:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:38:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:38:12 [3] Could not open pipe (timed out)
2010-08-02 07:38:12 [3] Service request '{"id": 1, "method": "getBlockLogin", "params": []}' failed
2010-08-02 07:38:12 [6] Last unblock time now set to: 1280727492
2010-08-02 07:38:12 [5] Not blocking!
2010-08-02 07:38:12 [5] LWlxLoggedOutSAS: Not blocking
2010-08-02 07:40:12 [5] Communicating with service through pipe
2010-08-02 07:40:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:40:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:40:22 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:40:22 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:40:32 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:40:32 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:40:42 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:40:42 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:40:52 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:40:52 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:02 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:02 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:22 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:22 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:32 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:32 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:42 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:42 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:41:52 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:41:52 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:02 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:02 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:12 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:12 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:12 [3] Could not open pipe (timed out)
2010-08-02 07:42:12 [3] Service request '{"id": 1, "method": "getBlockLogin", "params": []}' failed
2010-08-02 07:42:12 [6] Last unblock time now set to: 1280727732
2010-08-02 07:42:12 [5] Not blocking!
2010-08-02 07:42:12 [5] WlxDisplaySASNotice: Not blocking
2010-08-02 07:42:26 [5] Communicating with service through pipe
2010-08-02 07:42:26 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:26 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:36 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:36 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:46 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:46 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:42:56 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:42:56 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:06 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:06 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:16 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:16 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:26 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:26 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:36 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:36 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:46 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:46 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:43:56 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:43:56 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:44:06 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:44:06 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:44:16 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:44:16 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:44:26 [6] Creating file: \\.\pipe\opsiclientd
2010-08-02 07:44:26 [3] Could not open pipe \\.\pipe\opsiclientd: 2
2010-08-02 07:44:26 [3] Could not open pipe (timed out)
2010-08-02 07:44:26 [3] Service request '{"id": 1, "method": "getBlockLogin", "params": []}' failed
2010-08-02 07:44:26 [6] Last unblock time now set to: 1280727866
2010-08-02 07:44:26 [5] Not blocking!
2010-08-02 07:44:26 [5] LWlxLoggedOutSAS: Not blocking

Ich verstehe nicht ganz warum der Loginblocker nach dem 2min Timeout einen neuen Timeout beginnt.

Der Befehl c:\programme\opsi.org\preloginloader\notifier.exe -s notifier\action.ini generiert in dem Fall folgende Error-log:

Code: Alles auswählen

     line 793 in '<module>' in file 'notifier.py'
     line 106 in '__init__' in file 'notifier.py'
     line 162 in 'loadSkin' in file 'notifier.py'
     line 448 in 'parse' in file 'OPSI\Util\File\__init__.pyo'
     line 394 in 'parse' in file 'OPSI\Util\File\__init__.pyo'
     line 229 in 'readlines' in file 'OPSI\Util\File\__init__.pyo'
     line 212 in 'open' in file 'OPSI\Util\File\__init__.pyo'
     line 145 in 'open' in file 'OPSI\Util\File\__init__.pyo'
     line 870 in 'open' in file 'codecs.pyo'
ERROR: [Errno 2] No such file or directory: u'skin.ini'

Ich stehe z.Z. auf dem Schlauch wie ich das Problem beheben kann und bin für jeden Tip dankbar.

Besten Gruß,
Groo

Groo · Beitrag von **Groo** » 04 Aug 2010, 09:15

Hallo,

also das Problem ist, dass Windows beim Systemstart den Start des opsiclientd-Dienstes abbricht und der Loginblocker in einen 2min Timeout läuft - allerdings dann in manchen Fällen den Block nicht aufhebt und einen neuen Timeout beginnt.

Als Workaround werde ich den Standard-Systemdienststart-Timeout von 30sek auf 60sek erhöhen, wie hier beschrieben:
http://technet.microsoft.com/en-us/libr ... S.10).aspx

Dazu werde ich den Loginblocker für die Zeit in der keine Updates anstehen per Registry-Eintrag deaktivieren.
[HKEY_LOCAL_MACHINE\SOFTWARE\opsi.org\preloginloader] LoginBlockerStart 1 => 0

Das Ändern des Registry Eintrags würde ich gerne per Push-Event machen, ohne dass der Anwender davon etwas mitbekommt - allerdings öffnet sich bei allen meinen bisherigen Versuchen immer der notifier:
viewtopic.php?f=7&t=1635

Besten Gruß,
Groo

Groo · Beitrag von **Groo** » 13 Aug 2010, 09:39

Kurzes Feedback zum Workaround:

Wo vorher bei ca. 20% der Rechnerstarts der opsiclientd-Dienst nicht startete, ist nach Setzen des Registry Schlüssels der opsiclientd bei über 500 Systemstarts jedesmal gestartet:

Code: Alles auswählen

[HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Control] ServicesPipeTimeout <- REG_DWORD 60000

Der opsiclientd wird jetzt unter den Diensten immer als gestartet angezeigt, allerdings meldet er sich bei ca. 3% der Systemstarts nicht beim opsi-Server. In dem Fall legt er unter C:\tmp auch kein opsiclientd.log oder opsi_loginblocker.txt Log an.

Hat jemand ein ähnliches Problem oder eine Idee was die Ursache dafür sein könnte?

opsi-Neuling · Beitrag von **opsi-Neuling** » 16 Jan 2012, 16:49

Ich habe nun auch einen PC bei uns der dieses Problem zeigt.
Habe opsi über gewohnten Weg installiert und den PC neugestartet. Leider startet der opsiclientd nicht obwohl er auf automatisch gesetzt ist und sich manuell auch starten lässt. Im Eventlog des PCs finden sich keine Fehlermeldungen diesbezüglich.

Wir nutzen opsi-winst 4.11.1.6

Habe auch noch mal den Client Agent deinstalliert und neu installiert.

Das log_level in der opsiclientd.conf auf 9 zu setzen nutzt ja nicht wirklich, wenn der opsiclientd nicht startet.
Gibt es eine Möglichkeit das LoginBlockerLogLevel zu erhöhen? Ist momentan auf 6.

Gibt es noch eine Idee sich der Ursache anzunähern?

Grüße

Beitrag von **d.oertel** » 16 Jan 2012, 22:03

Hi,

Ich habe nun auch einen PC bei uns der dieses Problem zeigt.

Bitte mach doch in solchen Fällen nicht einen fast zwei Jahre alten Thread wieder auf, der sich auf vollkommen andere Softwarestände bezieht.

Man kann ja auf den alten Thread verweisen.

Was sagt denn der Windows eventlog ?

gruss
d.oertel

opsi-Neuling · Beitrag von **opsi-Neuling** » 17 Jan 2012, 11:05

Hallo!

Vielen Dank für die Antwort. Tut mir leid, diesen Thread benutzt zu haben, aber er bot sich so an, weil es offensichtlich um genau das gleiche Problem geht und über google gefunden wird. Aber ich gelobe Besserung!

Wie bereits geschrieben steht im Eventlog gar nichts problematisches von einem opsi Dienst bzw. der abhängen Dienste.
Ist halt die Frage ob man noch eine Möglichkeit hat heraus zu finden warum der opsiclientd Dienst nicht automatisch starten kann.

Grüße

bell · Beitrag von **bell** » 17 Jan 2012, 17:42

Hallo,

Irgendwelche Programme installiert die sich auch beim Start melden?
Was steht denn unter : [HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Winlogon] GinaDLL ?

MfG bell

Beitrag von **d.oertel** » 17 Jan 2012, 18:38

Hi,

mal auf den aktuellen Stand updaten
viewtopic.php?f=1&t=3216

Wenn das Problem dann noch da ist:
Was für ein Client (win7/xp/...)
c:\tmp\opsi-client-agent.log

gruss
d.oertel

forum.opsi.org

opsiclientd-Dienst startet nicht

opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht

Re: opsiclientd-Dienst startet nicht