Seite 1 von 1

[GELÖST]Verbindungfehler Client zu Config-Server: Backend au

Verfasst: 16 Apr 2012, 14:06
von nh84
Hallo,
ich bin gerade dabei testweise OPSI bei uns im Betrieb einzuführen. Ich halte mich dabei an das getting-startet, hänge nun aber leider fest. Ich bin soweit das der OPSI Server (Ubuntu 10.04 64-Bit) steht und auch die Verteilung der Clients über die service_setup.cmd oder über die Skript-Variante hat funktioniert. Mein Problem ist nun, dass unser Testclients anscheinend keine Verbindung zum OPSI-Config Server herstellen können. Es kommt die Meldung, dass keine Verbindung zum Config-Server hersgestellt werden konnte, da der Client nicht im Backend zu finden ist.
Danach versucht der Client erneut eine Verbindung zum Server herzustellen, was aber mit dem Ablaufen des Zählers nach 30 Sekunden abgebrochen wird.

Über das Webfrontend von opsi-configed hat sich der Client auch korrekt mit <hostname>.<domainname>.local gemeldet und auch die IP, MAC oder der Zustand (An/Aus) wird übermittel. Auch kann ich vom configed aus Meldungen verschicken oder den Rechner herunterfahren. Wie oben erwähnt kann ich auch einen on_demand Auftrag versenden. Der Client wird aktiv, schaft es aber wie gesagt nicht sich mit den Config-Server zu verbinden und bricht nach einem Timeout ab.

So sieht der letzte Eintrag in der Logfile des Clients aus:

Code: Alles auswählen

   (Events.pyo|1042)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'sync_completed{cache_ready}' added to event generator 'sync_completed'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'gui_startup' added to event generator 'gui_startup'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'gui_startup{cache_ready}' added to event generator 'gui_startup'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'on_demand' added to event generator 'on_demand'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'sync_completed{cache_ready_user_logged_in}' added to event generator 'sync_completed'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'gui_startup{user_logged_in}' added to event generator 'gui_startup'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'sync_completed' added to event generator 'sync_completed'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'software_on_demand' added to event generator 'software_on_demand'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event config 'on_demand{user_logged_in}' added to event generator 'on_demand'   (Events.pyo|1079)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event generator '<GUIStartupEventGenerator gui_startup>' started   (Opsiclientd.pyo|304)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Initializing event generator '<GUIStartupEventGenerator gui_startup>'   (Events.pyo|471)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Activating event generator '<GUIStartupEventGenerator gui_startup>'   (Events.pyo|479)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event generator '<CustomEventGenerator on_demand>' started   (Opsiclientd.pyo|304)
[6] [Apr 16 13:03:30] [ event generator on_demand     ] Initializing event generator '<CustomEventGenerator on_demand>'   (Events.pyo|471)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator software_on_demand] Initializing event generator '<SwOnDemandEventGenerator software_on_demand>'   (Events.pyo|471)
[6] [Apr 16 13:03:30] [ event generator on_demand     ] Activating event generator '<CustomEventGenerator on_demand>'   (Events.pyo|479)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event generator '<SwOnDemandEventGenerator software_on_demand>' started   (Opsiclientd.pyo|304)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Searching pids of process name winlogon.exe (session id: None)   (Windows.pyo|1169)
[6] [Apr 16 13:03:30] [ event generator software_on_demand] Activating event generator '<SwOnDemandEventGenerator software_on_demand>'   (Events.pyo|479)
[6] [Apr 16 13:03:30] [ event generator on_demand     ] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator panic         ] Initializing event generator '<PanicEventGenerator panic>'   (Events.pyo|471)
[6] [Apr 16 13:03:30] [ event generator software_on_demand] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator on_demand     ] Nothing to watch for   (Events.pyo|565)
[6] [Apr 16 13:03:30] [ event generator panic         ] Activating event generator '<PanicEventGenerator panic>'   (Events.pyo|479)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event generator '<PanicEventGenerator panic>' started   (Opsiclientd.pyo|304)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Found process winlogon.exe with matching name (pid 540, session 0)   (Windows.pyo|1191)
[6] [Apr 16 13:03:30] [ event generator panic         ] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator sync_completed] Initializing event generator '<SyncCompletedEventGenerator sync_completed>'   (Events.pyo|471)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Event generator '<SyncCompletedEventGenerator sync_completed>' started   (Opsiclientd.pyo|304)
[6] [Apr 16 13:03:30] [ event generator sync_completed] Activating event generator '<SyncCompletedEventGenerator sync_completed>'   (Events.pyo|479)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Waiting for gui startup (timeout: 120 seconds)   (Opsiclientd.pyo|311)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Testing preconditions of configs: [<EventConfig: gui_startup>, <EventConfig: gui_startup{cache_ready}>, <EventConfig: gui_startup{user_logged_in}>]   (Events.pyo|399)
[6] [Apr 16 13:03:30] [ event generator sync_completed] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Preconditions {} for event config 'gui_startup' fulfilled   (Events.pyo|403)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Preconditions {u'products_cached': True, u'config_cached': True} for event config 'gui_startup{cache_ready}' not fulfilled   (Events.pyo|407)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Initializing event generator '<GUIStartupEventGenerator wait_for_gui>'   (Events.pyo|471)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Preconditions {u'user_logged_in': True} for event config 'gui_startup{user_logged_in}' not fulfilled   (Events.pyo|407)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Activating event generator '<GUIStartupEventGenerator wait_for_gui>'   (Events.pyo|479)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Firing event '<ocdlib.Events.GUIStartupEvent object at 0x01CF0550>'   (Events.pyo|439)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Getting next event...   (Events.pyo|481)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Event info:   (Events.pyo|440)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Searching pids of process name winlogon.exe (session id: None)   (Windows.pyo|1169)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Starting FireEventThread for listeners: [<OpsiclientdNT5(Thread-1, started daemon 1616)>]   (Events.pyo|463)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Event generator '<GUIStartupEventGenerator gui_startup>' now deactivated after 1 event occurrences   (Events.pyo|485)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Calling processEvent on listener <OpsiclientdNT5(Thread-1, started daemon 1616)>   (Events.pyo|458)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Found process winlogon.exe with matching name (pid 540, session 0)   (Windows.pyo|1191)
[6] [Apr 16 13:03:30] [ event generator gui_startup   ] Event generator '<GUIStartupEventGenerator gui_startup>' exiting    (Events.pyo|496)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Processing event <ocdlib.Events.GUIStartupEvent object at 0x01CF0550>   (Opsiclientd.pyo|383)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Testing preconditions of configs: [<EventConfig: wait_for_gui>]   (Events.pyo|399)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Preconditions {} for event config 'wait_for_gui' fulfilled   (Events.pyo|403)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Firing event '<ocdlib.Events.GUIStartupEvent object at 0x01CF09D0>'   (Events.pyo|439)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Event info:   (Events.pyo|440)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Starting FireEventThread for listeners: [<ocdlib.Opsiclientd.WaitForGUI object at 0x01CF0490>]   (Events.pyo|463)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Calling processEvent on listener <ocdlib.Opsiclientd.WaitForGUI object at 0x01CF0490>   (Events.pyo|458)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Event generator '<GUIStartupEventGenerator wait_for_gui>' now deactivated after 1 event occurrences   (Events.pyo|485)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] GUI started   (Opsiclientd.pyo|170)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Event generator '<GUIStartupEventGenerator wait_for_gui>' exiting    (Events.pyo|496)
[6] [Apr 16 13:03:30] [ event processing gui_startup  ] Session id set to 0   (EventProcessing.pyo|172)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Creating local user 'pcpatch'   (Opsiclientd.pyo|198)
[6] [Apr 16 13:03:30] [ opsiclientd                   ] Admin group name is 'Administratoren'   (Windows.pyo|1466)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] Done waiting for GUI   (Opsiclientd.pyo|313)
[6] [Apr 16 13:03:30] [ opsiclientd                   ] Adding user 'pcpatch' to group 'Administratoren'   (Windows.pyo|1403)
[5] [Apr 16 13:03:30] [ event processing gui_startup  ] ============= EventProcessingThread for occurrcence of event 'gui_startup' started =============   (EventProcessing.pyo|1070)
[5] [Apr 16 13:03:30] [ event processing gui_startup  ] Starting notification server on port 44000   (EventProcessing.pyo|193)
[6] [Apr 16 13:03:30] [ event generator wait_for_gui  ] Notification server starting   (Message.pyo|610)
[6] [Apr 16 13:03:30] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:30] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:30] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:30] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:30] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:30] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:30] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:30] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:30] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:30] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[5] [Apr 16 13:03:31] [ event processing gui_startup  ] Notification server started   (EventProcessing.pyo|220)
[6] [Apr 16 13:03:31] [ event processing gui_startup  ] Action processor name 'opsi-winst', version '4.11.2.5'   (EventProcessing.pyo|350)
[5] [Apr 16 13:03:31] [ opsiclientd                   ] Block login now set to 'True'   (Opsiclientd.pyo|111)
[5] [Apr 16 13:03:31] [ event processing gui_startup  ] Starting notifier application in session '0'   (EventProcessing.pyo|321)
[6] [Apr 16 13:03:31] [ opsiclientd                   ] Searching pids of process name winlogon.exe (session id: 0)   (Windows.pyo|1169)
[6] [Apr 16 13:03:31] [ opsiclientd                   ] Found process winlogon.exe with matching name (pid 540, session 0)   (Windows.pyo|1191)
[5] [Apr 16 13:03:31] [ opsiclientd                   ] Executing: 'C:\Programme\opsi.org\opsi-client-agent\\opsiclientd_rpc.exe "<hostname>.<domäne>.local" "*** confidential ***" "4441" "setCurrentActiveDesktopName("0", System.getActiveDesktopName())"' in session '0' on desktop 'winsta0\winlogon'   (Windows.pyo|1348)
[6] [Apr 16 13:03:31] [ opsiclientd                   ] Process startet, pid: 208   (Windows.pyo|1351)
[6] [Apr 16 13:03:31] [ opsiclientd                   ] Waiting for process ending: 208 (timeout: 60 seconds)   (Windows.pyo|1354)
[6] [Apr 16 13:03:31] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:31] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:31] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:31] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[5] [Apr 16 13:03:31] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:31] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:31] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[6] [Apr 16 13:03:32] [ control server                ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x01CFB5A8> started processing   (Worker.pyo|244)
[5] [Apr 16 13:03:32] [ control server                ] New session created   (Session.pyo|179)
[5] [Apr 16 13:03:32] [ control server                ] Authorization request from <hostname>.<domäne>.local@127.0.0.1 (application: opsi jsonrpc module version 4.0.1)   (ControlServer.pyo|143)
[5] [Apr 16 13:03:32] [ opsiclientd                   ] -----> Executing: backend_getInterface()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:32] [ opsiclientd                   ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:32] [ control server                ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x01CFB288> started processing   (Worker.pyo|244)
[6] [Apr 16 13:03:32] [ control server                ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.1'   (Worker.pyo|391)
[5] [Apr 16 13:03:32] [ control server                ] Authorization request from <hostname>.<domäne>.local@127.0.0.1 (application: opsi jsonrpc module version 4.0.1)   (ControlServer.pyo|143)
[5] [Apr 16 13:03:32] [ opsiclientd                   ] -----> Executing: setCurrentActiveDesktopName(0, u'Winlogon')   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:32] [ opsiclientd                   ] rpc setCurrentActiveDesktopName: current active desktop name for session 0 set to 'Winlogon'   (ControlServer.pyo|485)
[6] [Apr 16 13:03:32] [ opsiclientd                   ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:32] [ control server                ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x01D03170> started processing   (Worker.pyo|244)
[6] [Apr 16 13:03:32] [ control server                ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.1'   (Worker.pyo|391)
[5] [Apr 16 13:03:32] [ control server                ] Authorization request from <hostname>.<domäne>.local@127.0.0.1 (application: opsi jsonrpc module version 4.0.1)   (ControlServer.pyo|143)
[5] [Apr 16 13:03:32] [ opsiclientd                   ] -----> Executing: backend_exit()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:32] [ opsiclientd                   ] Got result   (JsonRpc.pyo|131)
[5] [Apr 16 13:03:32] [ opsiclientd                   ] Process 208 ended with exit code 0   (Windows.pyo|1364)
[6] [Apr 16 13:03:32] [ event processing gui_startup  ] Running command C:\Programme\opsi.org\opsi-client-agent\\notifier.exe -p 44000 -i event -s notifier\\event.ini in session '0' on desktop 'winlogon'   (EventProcessing.pyo|297)
[6] [Apr 16 13:03:32] [ event processing gui_startup  ] Searching pids of process name winlogon.exe (session id: 0)   (Windows.pyo|1169)
[6] [Apr 16 13:03:32] [ event processing gui_startup  ] Found process winlogon.exe with matching name (pid 540, session 0)   (Windows.pyo|1191)
[5] [Apr 16 13:03:32] [ event processing gui_startup  ] Executing: 'C:\Programme\opsi.org\opsi-client-agent\\notifier.exe -p 44000 -i event -s notifier\\event.ini' in session '0' on desktop 'winsta0\winlogon'   (Windows.pyo|1348)
[6] [Apr 16 13:03:32] [ event processing gui_startup  ] Process startet, pid: 348   (Windows.pyo|1351)
[6] [Apr 16 13:03:32] [ event processing gui_startup  ] Session id set to 0   (EventProcessing.pyo|172)
[5] [Apr 16 13:03:32] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:32] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:32] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[6] [Apr 16 13:03:32] [ opsiclientd                   ] client connection made   (Message.pyo|459)
[6] [Apr 16 13:03:33] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:33] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:33] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:33] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:33] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:33] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:33] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:33] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:33] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:33] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:34] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:34] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:34] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:34] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:34] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:34] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:34] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:34] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:34] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:34] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:35] [ event processing gui_startup  ] User is allowed to cancel connection after 30 seconds   (EventProcessing.pyo|130)
[6] [Apr 16 13:03:35] [ event processing gui_startup  ] Starting ServiceConnectionThread, timeout is 30 seconds   (OpsiService.pyo|163)
[5] [Apr 16 13:03:35] [ service connection            ] Connecting to config server 'https://<IP-OPSI-Server>:4447/rpc/rpc' #1   (OpsiService.pyo|308)
[6] [Apr 16 13:03:35] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:35] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:35] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:35] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:35] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:35] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:35] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:35] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:35] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:35] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[2] [Apr 16 13:03:36] [ opsiclientd                   ] Traceback:   (Logger.pyo|742)
[2] [Apr 16 13:03:36] [ opsiclientd                   ]      line 94 in 'processResult' in file 'OPSI\Backend\JSONRPC.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:36] [ opsiclientd                   ]      ==>>> Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (JSONRPC.pyo|98)
[3] [Apr 16 13:03:36] [ service connection            ] Failed to connect to config server 'https://<IP-OPSI-Server>:4447/rpc/rpc': Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (OpsiService.pyo|340)
[5] [Apr 16 13:03:36] [ service connection            ] Connect failed with username '<hostname>.<domäne>', got fqdn '<hostname>.<domäne>.local' from os, trying fqdn   (OpsiService.pyo|349)
[6] [Apr 16 13:03:36] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:36] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:36] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:36] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:36] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:36] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:36] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:36] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:36] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:36] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:37] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:37] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:37] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:37] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:37] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:37] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:37] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:37] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:37] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:37] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:38] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:38] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:38] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'True'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:38] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:38] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:38] [ control pipe                  ] -----> Executing: isShutdownRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:38] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:38] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[5] [Apr 16 13:03:38] [ control pipe                  ] -----> Executing: isRebootRequested()   (JsonRpc.pyo|123)
[6] [Apr 16 13:03:38] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[5] [Apr 16 13:03:39] [ service connection            ] Connecting to config server 'https://<IP-OPSI-Server>/rpc/rpc' #2   (OpsiService.pyo|308)
[2] [Apr 16 13:03:39] [ control pipe                  ] Traceback:   (Logger.pyo|742)
[2] [Apr 16 13:03:39] [ control pipe                  ]      line 94 in 'processResult' in file 'OPSI\Backend\JSONRPC.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:39] [ control pipe                  ]      ==>>> Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (JSONRPC.pyo|98)
[3] [Apr 16 13:03:39] [ service connection            ] Failed to connect to config server 'https://<IP-OPSI-Server>:4447/rpc/rpc': Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (OpsiService.pyo|340)
[3] [Apr 16 13:03:39] [ event processing gui_startup  ] Failed to connect to config service 'https://<IP-OPSI-Server>:4447/rpc/rpc': Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (OpsiService.pyo|111)
[5] [Apr 16 13:03:39] [ event processing gui_startup  ] Writing log to service   (EventProcessing.pyo|256)
[4] [Apr 16 13:03:39] [ event processing gui_startup  ] Cannot write log to service: not connected   (EventProcessing.pyo|259)
[6] [Apr 16 13:03:39] [ opsiclientd                   ] shutdownRequested: 0   (Windows.pyo|340)
[4] [Apr 16 13:03:39] [ opsiclientd                   ] Failed to get rebootRequested from registry: [Error 2] Das System kann die angegebene Datei nicht finden   (Windows.pyo|325)
[6] [Apr 16 13:03:39] [ opsiclientd                   ] rebootRequested: 0   (Windows.pyo|326)
[5] [Apr 16 13:03:39] [ opsiclientd                   ] Block login now set to 'False'   (Opsiclientd.pyo|111)
[6] [Apr 16 13:03:39] [ opsiclientd                   ] Terminating block login notifier app (pid 1808)   (Opsiclientd.pyo|148)
[6] [Apr 16 13:03:39] [ event processing gui_startup  ] Stopping notification server   (EventProcessing.pyo|234)
[5] [Apr 16 13:03:39] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:03:39] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'False'   (ControlPipe.pyo|428)
[6] [Apr 16 13:03:39] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:03:39] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
[6] [Apr 16 13:03:40] [ opsiclientd                   ] client connection lost   (Message.pyo|464)
[6] [Apr 16 13:03:41] [ event generator wait_for_gui  ] Notification server stopped   (Message.pyo|652)
[3] [Apr 16 13:03:44] [ event processing gui_startup  ] Failed to process event <ocdlib.Events.GUIStartupEvent object at 0x01CF0550>: Failed to connect to config service 'https://<IP-OPSI-Server>:4447/rpc/rpc': Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (EventProcessing.pyo|1220)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ] Traceback:   (Logger.pyo|742)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ]      line 1154 in 'run' in file 'ocdlib\EventProcessing.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ]      line 189 in 'connectConfigService' in file 'ocdlib\OpsiService.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ]      line 166 in 'connectionFailed' in file 'ocdlib\EventProcessing.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ]      line 112 in 'connectionFailed' in file 'ocdlib\OpsiService.pyo'   (Logger.pyo|742)
[2] [Apr 16 13:03:44] [ event processing gui_startup  ]      ==>>> Failed to connect to config service 'https://<IP-OPSI-Server>:4447/rpc/rpc': Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname>.<domäne>.local' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (error on server)   (EventProcessing.pyo|1221)
[5] [Apr 16 13:03:44] [ opsiclientd                   ] Block login now set to 'False'   (Opsiclientd.pyo|111)
[5] [Apr 16 13:03:44] [ event processing gui_startup  ] ============= EventProcessingThread for event 'gui_startup' ended =============   (EventProcessing.pyo|1230)
[5] [Apr 16 13:03:44] [ opsiclientd                   ] Done processing event '<ocdlib.Events.GUIStartupEvent object at 0x01CF0550>'   (Opsiclientd.pyo|418)
[5] [Apr 16 13:03:44] [ opsiclientd                   ] Deleting local user 'pcpatch'   (Opsiclientd.pyo|215)
[6] [Apr 16 13:03:44] [ opsiclientd                   ] Failed to delete user profile 'pcpatch' (sid S-1-5-21-682003330-1659004503-725345543-1058): (2, 'DeleteProfile', 'Das System kann die angegebene Datei nicht finden.')   (Windows.pyo|1424)
[5] [Apr 16 13:04:12] [ control pipe                  ] -----> Executing: getBlockLogin()   (JsonRpc.pyo|123)
[5] [Apr 16 13:04:12] [ opsiclientd                   ] rpc getBlockLogin: blockLogin is 'False'   (ControlPipe.pyo|428)
[6] [Apr 16 13:04:12] [ control pipe                  ] Got result   (JsonRpc.pyo|131)
[6] [Apr 16 13:04:12] [ control pipe                  ] Creating pipe \\.\pipe\opsiclientd   (ControlPipe.pyo|253)
IPs und FQDNs sind anonymisiert, sie scheine aber nach meiner Prüfung zu stimmen (d.h. IP des OPSI Servers ist korrekt, Client korrekt mit FQDN angegeben)

Es würde mich freuen, wenn mir jemand ein Tipp geben könnte, was hier schief laufen könnte und was nachzukonfigurieren ist.

MfG

Re: Verbindungfehler Client zu Config-Server: Backend authen

Verfasst: 16 Apr 2012, 14:34
von d.oertel
Hi,

naja - ich versteh das zwar mit der Anonymisierung aber wahrscheinlich liegt das Problem doch genau in diesen Daten.

Vergleiche doch mal die gepostete Logdatei mit der entsprechenden Logdatei des opsiconfd, welcher ja die Authentifizierung ablehnt.

gruss
d.oertel

Re: Verbindungfehler Client zu Config-Server: Backend authen

Verfasst: 16 Apr 2012, 16:51
von nh84
Hallo,
danke schon mal für die fixe Reaktion. Habe mir die opsiconfd.log mal angeschaut den Auszug der unseren Testclient betrifft herauskopiert:

Code: Alles auswählen

[5] [Apr 16 13:04:26] Session '6DFEjOp3pydWHFA1uqxQvx6cZhF92QMn' from ip '<Host_IP>', application 'opsi-winst 4.11.2.5' expired after 120 seconds (Session.py|184)
[5] [Apr 16 13:04:26] Session '6DFEjOp3pydWHFA1uqxQvx6cZhF92QMn' from ip '<Host_IP>', application 'opsi-winst 4.11.2.5' deleted (Session.py|211)
[5] [Apr 16 13:04:44] Session 'njX2ZSP44iAdZ93pSZGTD3WD59pku8ri' from ip '<Host_IP>', application 'opsi-winst 4.11.2.5' expired after 120 seconds (Session.py|184)
[5] [Apr 16 13:04:44] Session 'njX2ZSP44iAdZ93pSZGTD3WD59pku8ri' from ip '<Host_IP>', application 'opsi-winst 4.11.2.5' deleted (Session.py|211)
<Host_IP> ist dabei die korekte Adresse des Clients welcher sich mit den Config-Server verbinden soll.

Die Meldung im Clientlog

Code: Alles auswählen

==>>> Opsi authentication error: Forbidden: Backend authentication error: Host '<hostname.domäne.local>' not found in backend <OPSI.Backend.BackendManager.BackendManager instance at 0x2b7d5a8> (Worker.py|289)

macht mich etwas stutzig. Kann es sein das ein Fehler in der Konfiguration des Backends vorliegt (<hostname.domäne.local> ist dabei ein Platzhalter für den eingentlich korekt dargestellten FQDN des Clients)?

Edit: habe gerade Versucht den opsi-client-agent über den FQDN des Host noch mal zu installieren. Hier scheint es das Problem zu geben, dass ein Unterstich im Domainnamen als Minus umgesetzt wird und der Server so keine passende IP vom Client bekommt. Gebe ich nur den Hostname ohne Domäne an, wird der Client erfolgreich ausgerollt, bekommt nach dem Booten aber keine Verbindung zum Config-Server hin. Kann es sein das der Unterstich im Namen der Domäne das große Problem ist?

Edit 2: liegt tatsächlich an dem Unterstich in Domainname. Habe über die /etc/network/hosts einen manuellen Eintrag für den Host mit - stat _ angelegt und schon hat die Installation über das Deployment-Skript hat funktioniert, der Client findet den Config-Server und hw- und swaudit konnten erfolgreich verteilt werden. Da das Problem am Domainname hängt und nicht an OPSI ansich habe ich das Topic aufgelöst gesetzt.

MfG

Re: [GELÖST]Verbindungfehler Client zu Config-Server: Backen

Verfasst: 17 Apr 2012, 13:39
von d.oertel