opsiclientd Event "wait_for_gui" Endlosschleife

[AlexB]

Hallo zusammen,

Genutzt wird:
opsiclientd 4.1.0.0-40 (Paketversion)
Windows 10 1909

Problem:
Wenn der opsiclientd Service neugestartet wird (während Windows läuft), fragt er dauerhaft ab ob der Prozess "LogonUI.exe" gestartet ist. Selbst nach dem Timeout, das man in der Config setzt.

Erwartete Funktion:
Es wird nach dem Timeout zum nächsten Event übergegangen (?).

Log:

Code: Alles auswählen

[5] [May 18 12:26:52] [ opsiclientd                   ] Config read   (Config.pyo|335)
[5] [May 18 12:26:52] [ opsiclientd                   ] Setting locale dir to 'C:\Program Files (x86)\opsi.org\opsi-client-agent\locale'   (Localization.pyo|59)
[5] [May 18 12:26:52] [ opsiclientd                   ] Loading translation for language 'de'   (Localization.pyo|61)
[1] [May 18 12:26:52] [ opsiclientd                   ] Opsiclientd version: 4.0.99 (full)   (Opsiclientd.pyo|234)
[1] [May 18 12:26:52] [ opsiclientd                   ] Commandline: C:\Program Files (x86)\opsi.org\opsi-client-agent\opsiclientd.exe   (Opsiclientd.pyo|239)
[1] [May 18 12:26:52] [ opsiclientd                   ] Working directory: C:\WINDOWS\system32   (Opsiclientd.pyo|241)
[5] [May 18 12:26:52] [ opsiclientd                   ] Using host id 'xxx'   (Opsiclientd.pyo|243)
[5] [May 18 12:26:52] [ opsiclientd                   ] Block login now set to 'True'   (Opsiclientd.pyo|109)
[6] [May 18 12:26:52] [ opsiclientd                   ] Starting block login notifier app   (Opsiclientd.pyo|119)
[7] [May 18 12:26:52] [ opsiclientd                   ] Session id given: 1   (Windows.pyo|1613)
[6] [May 18 12:26:52] [ opsiclientd                   ] Searching pids of process name winlogon.exe (session id: 1)   (Windows.pyo|1447)
[6] [May 18 12:26:52] [ opsiclientd                   ] Found process winlogon.exe with matching name (pid 1220, session 1)   (Windows.pyo|1470)
[5] [May 18 12:26:52] [ opsiclientd                   ] Executing: 'C:\Program Files (x86)\opsi.org\opsi-client-agent\\notifier.exe -s notifier\\block_login.ini' in session '1' on desktop 'winsta0\winlogon'   (Windows.pyo|1634)
[6] [May 18 12:26:52] [ opsiclientd                   ] Process startet, pid: 11824   (Windows.pyo|1637)
[7] [May 18 12:26:52] [ opsiclientd                   ] Waiting for reactor   (Opsiclientd.pyo|256)
[5] [May 18 12:26:52] [ opsiclientd                   ] Starting reactor   (Opsiclientd.pyo|249)
[5] [May 18 12:26:53] [ opsiclientd                   ] Starting control pipe   (Opsiclientd.pyo|262)
[6] [May 18 12:26:53] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|251)
[5] [May 18 12:26:53] [ opsiclientd                   ] Control pipe started   (Opsiclientd.pyo|266)
[5] [May 18 12:26:53] [ opsiclientd                   ] Starting control server   (Opsiclientd.pyo|271)
[7] [May 18 12:26:53] [ control pipe                  ] Pipe \\.\pipe\opsiclientd created   (ControlPipe.pyo|270)
[7] [May 18 12:26:53] [ control pipe                  ] Connecting to named pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|291)
[6] [May 18 12:26:53] [ control server                ] ControlServer initiated   (ControlServer.pyo|358)
[6] [May 18 12:26:53] [ control server                ] creating root resource   (ControlServer.pyo|365)
[5] [May 18 12:26:53] [ opsiclientd                   ] Control server started   (Opsiclientd.pyo|280)
[5] [May 18 12:26:53] [ opsiclientd                   ] Starting cache service   (Opsiclientd.pyo|288)
[5] [May 18 12:26:53] [ control server                ] Control server is accepting HTTPS requests on port 4441   (ControlServer.pyo|372)
[5] [May 18 12:26:53] [ opsiclientd                   ] Cache service started   (Opsiclientd.pyo|292)
[5] [May 18 12:26:53] [ control server                ] Control server exiting   (ControlServer.pyo|378)
[5] [May 18 12:26:53] [ opsiclientd                   ] Event config 'on_shutdown' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:53] [ opsiclientd                   ] Event config 'timer' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:53] [ opsiclientd                   ] Event config 'user_login' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:53] [ opsiclientd                   ] Event config 'net_connection' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'gui_startup' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'silent_install' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'on_demand' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'sync_completed' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'software_on_demand' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator 'timer_silentinstall' created   (Events.pyo|1092)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'on_shutdown' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'timer' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'user_login' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'net_connection' is deactivated   (Events.pyo|920)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'gui_startup' added to event generator 'gui_startup'   (Events.pyo|1110)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'silent_install' added to event generator 'silent_install'   (Events.pyo|1110)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'on_demand' added to event generator 'on_demand'   (Events.pyo|1110)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'sync_completed' added to event generator 'sync_completed'   (Events.pyo|1110)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'software_on_demand' added to event generator 'software_on_demand'   (Events.pyo|1110)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event config 'timer_silentinstall' added to event generator 'timer_silentinstall'   (Events.pyo|1110)
[6] [May 18 12:26:54] [ event generator on_demand     ] Initializing event generator '<CustomEventGenerator on_demand>'   (Events.pyo|477)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<CustomEventGenerator on_demand>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator on_demand     ] Activating event generator '<CustomEventGenerator on_demand>'   (Events.pyo|485)
[6] [May 18 12:26:54] [ event generator gui_startup   ] Initializing event generator '<GUIStartupEventGenerator gui_startup>'   (Events.pyo|477)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<GUIStartupEventGenerator gui_startup>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator on_demand     ] Getting next event...   (Events.pyo|487)
[6] [May 18 12:26:54] [ event generator gui_startup   ] Activating event generator '<GUIStartupEventGenerator gui_startup>'   (Events.pyo|485)
[6] [May 18 12:26:54] [ event generator silent_install] Initializing event generator '<CustomEventGenerator silent_install>'   (Events.pyo|477)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<CustomEventGenerator silent_install>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator on_demand     ] Nothing to watch for   (Events.pyo|571)
[6] [May 18 12:26:54] [ event generator gui_startup   ] Getting next event...   (Events.pyo|487)
[6] [May 18 12:26:54] [ event generator silent_install] Activating event generator '<CustomEventGenerator silent_install>'   (Events.pyo|485)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<SyncCompletedEventGenerator sync_completed>' started   (Opsiclientd.pyo|305)
[7] [May 18 12:26:54] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:26:54] [ event generator sync_completed] Initializing event generator '<SyncCompletedEventGenerator sync_completed>'   (Events.pyo|477)
[6] [May 18 12:26:54] [ event generator silent_install] Getting next event...   (Events.pyo|487)
[6] [May 18 12:26:54] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[6] [May 18 12:26:54] [ event generator sync_completed] Activating event generator '<SyncCompletedEventGenerator sync_completed>'   (Events.pyo|485)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<SwOnDemandEventGenerator software_on_demand>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator software_on_demand] Initializing event generator '<SwOnDemandEventGenerator software_on_demand>'   (Events.pyo|477)
[6] [May 18 12:26:54] [ event generator silent_install] Nothing to watch for   (Events.pyo|571)
[6] [May 18 12:26:54] [ event generator sync_completed] Getting next event...   (Events.pyo|487)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<PanicEventGenerator panic>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator panic         ] Initializing event generator '<PanicEventGenerator panic>'   (Events.pyo|477)
[6] [May 18 12:26:54] [ event generator software_on_demand] Activating event generator '<SwOnDemandEventGenerator software_on_demand>'   (Events.pyo|485)
[6] [May 18 12:26:54] [ event generator panic         ] Activating event generator '<PanicEventGenerator panic>'   (Events.pyo|485)
[6] [May 18 12:26:54] [ event generator software_on_demand] Getting next event...   (Events.pyo|487)
[5] [May 18 12:26:54] [ opsiclientd                   ] Event generator '<TimerEventGenerator timer_silentinstall>' started   (Opsiclientd.pyo|305)
[6] [May 18 12:26:54] [ event generator timer_silentinstall] Initializing event generator '<TimerEventGenerator timer_silentinstall>'   (Events.pyo|477)
[6] [May 18 12:26:54] [ event generator panic         ] Getting next event...   (Events.pyo|487)
[6] [May 18 12:26:54] [ event generator timer_silentinstall] Activating event generator '<TimerEventGenerator timer_silentinstall>'   (Events.pyo|485)
[5] [May 18 12:26:54] [ opsiclientd                   ] Waiting for gui startup (timeout: 10 seconds)   (Opsiclientd.pyo|312)
[6] [May 18 12:26:54] [ event generator timer_silentinstall] Getting next event...   (Events.pyo|487)
[6] [May 18 12:26:54] [ event generator wait_for_gui  ] Initializing event generator '<GUIStartupEventGenerator wait_for_gui>'   (Events.pyo|477)
[7] [May 18 12:26:54] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[6] [May 18 12:26:54] [ event generator wait_for_gui  ] Activating event generator '<GUIStartupEventGenerator wait_for_gui>'   (Events.pyo|485)
[6] [May 18 12:26:54] [ event generator wait_for_gui  ] Getting next event...   (Events.pyo|487)
[7] [May 18 12:26:54] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:26:54] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:26:55] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:26:57] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:26:57] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:26:57] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:26:58] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:26:58] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:26:58] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:00] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:00] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:00] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:01] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:01] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:01] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:03] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:04] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:04] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:04] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:04] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:04] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[4] [May 18 12:27:04] [ opsiclientd                   ] Timed out after 10 seconds while waiting for GUI   (Opsiclientd.pyo|174)
[5] [May 18 12:27:04] [ opsiclientd                   ] Done waiting for GUI   (Opsiclientd.pyo|314)
[7] [May 18 12:27:07] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:07] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:07] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:07] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:07] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:07] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[5] [May 18 12:27:09] [ opsiclientd                   ] No events processing, unblocking login   (Opsiclientd.pyo|320)
[5] [May 18 12:27:09] [ opsiclientd                   ] Block login now set to 'False'   (Opsiclientd.pyo|109)
[6] [May 18 12:27:09] [ opsiclientd                   ] Terminating block login notifier app (pid 11824)   (Opsiclientd.pyo|146)
[7] [May 18 12:27:10] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:10] [ event generator gui_startup   ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:10] [ event generator gui_startup   ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:10] [ event generator wait_for_gui  ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)
[6] [May 18 12:27:10] [ event generator wait_for_gui  ] Searching pids of process name LogonUI.exe (session id: None)   (Windows.pyo|1447)
[7] [May 18 12:27:10] [ event generator wait_for_gui  ] No process with name LogonUI.exe found (session id: None)   (Windows.pyo|1477)
[7] [May 18 12:27:13] [ event generator gui_startup   ] Checking if process 'LogonUI.exe' running   (Events.pyo|626)

Genaueres Log (Loglevel 8) nur per PM.

VG
Alex

[Jan.Schmidt]

Problem:
Wenn der opsiclientd Service neugestartet wird (während Windows läuft)

Genaueres Log (Loglevel 8) nur per PM.

VG
Alex

Hi,

kannst du uns einen Tipp geben, warum der Dienst im laufenden Betrieb neugestartet wird?

[AlexB]

kannst du uns einen Tipp geben, warum der Dienst im laufenden Betrieb neugestartet wird?

Immer dann wenn der Client mal wieder nicht ordnungsgemäß funktioniert:

- Client über configed nicht errreichbar
- Silent oder ondemand funktionieren nicht mehr (Client will Port erneut öffnen, obwohl Client schon auf dem Port läuft)

In 90% der Fällen läuft das leider zu dem oben beschriebenen Problem hinaus. Ab und an funktioniert es aber ohne Probleme.

[Jan.Schmidt]

kannst du uns einen Tipp geben, warum der Dienst im laufenden Betrieb neugestartet wird?

Immer dann wenn der Client mal wieder nicht ordnungsgemäß funktioniert:

- Client über configed nicht errreichbar

Hi,

das ist mir etwas zu vage.
Wenn z.B die IP im opsi nicht mit der ip des clients übereinstimmt, weil (warum auch immer) hast du das gleiche phänomen "Client über configed nicht errreichbar". Stimmen denn die IPs überein?

[SirTux]

Bei m ir zumindest stimmen die IPs:
viewtopic.php?f=7&t=11820

[AlexB]

Wenn z.B die IP im opsi nicht mit der ip des clients übereinstimmt, weil (warum auch immer) hast du das gleiche phänomen "Client über configed nicht errreichbar". Stimmen denn die IPs überein?

Die IPs im configed sind irrelevant, da alles über den Domainnamen läuft.
Der DN des Clients ist in dem Falle auch vom Server aus korrekt. Der Server spricht den Client an und bekommt aber nichts zurück, da der Client halt nicht reagiert. Keine Ahnung warum.
Das ist aber auch ein anderes Thema.

[Jan.Schmidt]

Wenn z.B die IP im opsi nicht mit der ip des clients übereinstimmt, weil (warum auch immer) hast du das gleiche phänomen "Client über configed nicht errreichbar". Stimmen denn die IPs überein?

Die IPs im configed sind irrelevant, da alles über den Domainnamen läuft.

Je nachdem, was du bei resolveHostAddress = reinschreibst stimmt die Aussage oder nicht.
Daher gilt:

das ist mir etwas zu vage.

[AlexB]

Je nachdem, was du bei resolveHostAddress = reinschreibst stimmt die Aussage oder nicht.

Liest du eigentlich auch was ich schreibe?
Habe doch oben schon gesagt, dass die IP ordentlich aufgelöst wird und der Client angesprochen wird!

Daher gilt:

Außer das du hier irgendwie den Thread derailen willst, ist das mir das recht egal was bei DIR gilt.

[Jan.Schmidt]

Genaueres Log (Loglevel 8) nur per PM.

Sicherlich muß es ein Bug sein, denn jeder hat das Problem.
Auf gar keinen Fall kann es irgendwas mit Ihrem Netzwerk zu tun haben und die Firewallregeln können es schon aus Prinzip nicht sein.

Tut mich wahnsinnig traurig, das ich beim "derailen" ertappt wurde und Ihnen nicht weiterhelfen kann.

Keine Hilfe per PM ist leider das was ich aus Foren kenne und mit den netten Worten verabschiede mich von Ihrem Problem dessen Lösung ich fälschlicherweise zu meinem gemacht habe.
Wird garantiert nicht wieder vorkommen.

Mit vielen Grüßen

[CaS4YumEE35lJo1UNAhr]

Hallo Alex,

hst Du die vorherige Version des opsi-client-agents (< 40) zur Verfügung? Evtl. lohnt sich ein Test damit, dann könnte man evtl. hinsichtlich Bugs / Änderungen einschränken. Hat bei uns in einem ähnlich gelagerten Fall (viewtopic.php?f=7&t=11805&p=51607#p51607) so funktioniert, ist zumindest ein Workaround für uns.