GELÖST: Installation von OPSI-Paketen unter V3.4 nach XP

[V. Sternemann]

Hallo!

Ich hatte gehofft, mich hier ohne nervige Fragen im Forum durchbeißen zu können. Tut mir leid, dass es doch nicht so ist.

Folgende Ausgangssituation: OPSI soll hier zunächst in einer Testumgebung zum Deploy von Betriebssystemen, deren Treibern und weiterer Software, die wir noch paketieren müssen, eingesetzt werden. Bislang (unter OPSI 3.3.1) ging das soweit in der Umgebung ganz gut. Seit gestern habe ich dann mal einen komplett neuen OPSI-Server aufgesetzt, weil später im Echtbetrieb auch der Deploy von Win7 geplant ist. Derzeit ist das aber noch nicht Thema und sollte an dieser Stelle auch nur als Randinformation gedacht sein, warum wir auf OPSI 3.4 nicht verzichten können.

Das Problem: Die Clients (derzeit sowohl reale als auch virtuelle Maschinen) lassen sich inzwischen wieder nach anfänglichen Schwierigkeiten bei der Anpassung der Einstellungen hervorragend unter OPSI 3.4 installieren. Auch die automatische Einbindung in die Domöne, das Erstellen der Einträge in der AD an der richtigen Stelle klappt super, die Gruppenrichtlinienübergabe... undundund... Soweit alles bestens.

ABER: Ich möchte natürlich noch weitere Pakete installieren und da setzen die Probleme ein. Unter 3.3.1 ging alles problemlos. Aber unter 3.4 läuft in diesem Punkt leider gar nix mehr. Die zu installierenden Clients installieren WinXP, rebooten, starten die neue Oberfläche vom Opsiclientd und verbinden sich auch anscheinend mit dem Server. Allerdings wird dann abgebrochen mit dem Fehler

"Failed to process product action requests: 'vclientxphost.schulePN.local' (opsiclientd.pyo|3169)"

Ich kapier' einfach nicht, warum die weitere Installation hier scheitert. Dass sie scheitert, sehe ich ja selbst, aber mehr kann ich leider aus dem Log-Eintrag auch nicht entnehmen.

Übrigens erscheint bei jedem neuen Reboot das gleiche Spiel, selbst wenn ich im Opsi Configuration Editor überhaupt kein Paket mehr zur Installation angewählt habe.

Kann mir da bitte jemand helfen?

Danke schön im voraus!

MfG,

V. Sternemann

[j.schneider]

Hallo,

ich vermute, dass die Modules-Datei nicht unter /etc/opsi abgelegt wurde.
Die Modules-Datei kann hier heruntergeladen werden:
http://download.uib.de/opsi3.4/modules
Sollte das nicht helfen, bitte die Clientconnect-Log hier posten.

Gruß
Jan Schneider

[V. Sternemann]

Hallo Herr Schneider!

Danke für die schnelle Hilfe. Doch, die modules-Datei ist unter /etc/opsi hinterlegt und lässt sich auch korrekt im configuration editor abfragen. Das ist ja mein Problem, sonst wäre es ja einfach

Welche Clientconnect-Log meinen Sie? Meinen Sie die "opsiclientd.log"? Das wäre dann die hier:

Code: Alles auswählen

[5] [Jul 28 10:31:20] [opsiclientd]   Now using log-file 'c:\\tmp\\opsiclientd.log'     (Logger.pyo|333)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value action_processor.local_dir     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value action_processor.remote_dir     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value action_processor.command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value action_processor.filename     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_daemon_shutdown.active     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_daemon_shutdown.type     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value notification_server.interface     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value notification_server.port     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.log_file     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Now using log-file 'c:\\tmp\\opsiclientd.log'     (Logger.pyo|333)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.log_level     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.host_id     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.wait_for_gui_timeout     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.wait_for_gui_application     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value global.opsi_host_key     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value control_server.ssl_server_cert_file     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value control_server.static_dir     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value control_server.ssl_server_key_file     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value control_server.interface     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value control_server.port     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value opsiclientd_rpc.command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value depot_server.url     (opsiclientd.pyo|2151)
[3] [Jul 28 10:31:20] [opsiclientd]   Refusing to set empty value for config value 'url' of section 'depot_server'     (opsiclientd.pyo|2155)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value depot_server.drive     (opsiclientd.pyo|2151)
[3] [Jul 28 10:31:20] [opsiclientd]   Refusing to set empty value for config value 'drive' of section 'depot_server'     (opsiclientd.pyo|2155)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value depot_server.depot_id     (opsiclientd.pyo|2151)
[3] [Jul 28 10:31:20] [opsiclientd]   Refusing to set empty value for config value 'depot_id' of section 'depot_server'     (opsiclientd.pyo|2155)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_daemon_startup.active     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_daemon_startup.type     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.cache_products     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.use_cached_config     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.requires_cached_products     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.block_login     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.user_cancelable     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.cache_max_bandwidth     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.lock_workstation     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.sync_config     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.message     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.write_log_to_service     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.update_config_file     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.event_notifier_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.get_config_from_service     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.logoff_current_user     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.action_notifier_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.action_notifier_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.action_processor_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.event_notifier_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.action_processor_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.type     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_gui_startup.update_action_processor     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value opsiclientd_notifier.command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.cache_products     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.write_log_to_service     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.cache_max_bandwidth     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.wql     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.active     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.message     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.update_config_file     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.event_notifier_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.get_config_from_service     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.action_notifier_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.warning_time     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.service_options     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.event_notifier_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.action_processor_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.action_processor_command     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.type     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value event_vpn_startup.action_notifier_desktop     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value cache_service.backend_manager_config     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value config_service.url     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value config_service.server_id     (opsiclientd.pyo|2151)
[3] [Jul 28 10:31:20] [opsiclientd]   Refusing to set empty value for config value 'server_id' of section 'config_service'     (opsiclientd.pyo|2155)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value config_service.user_cancellable_after     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:20] [opsiclientd]   Setting config value config_service.connection_timeout     (opsiclientd.pyo|2151)
[4] [Jul 28 10:31:20] [opsiclientd]   Config read     (opsiclientd.pyo|2227)
[0] [Jul 28 10:31:20] [opsiclientd]   Opsiclientd version: 0.5.7     (opsiclientd.pyo|2543)
[0] [Jul 28 10:31:20] [opsiclientd]   Commandline: C:\Programme\opsi.org\preloginloader\opsiclientd.exe     (opsiclientd.pyo|2544)
[0] [Jul 28 10:31:20] [opsiclientd]   Working directory: C:\WINDOWS\system32     (opsiclientd.pyo|2545)
[4] [Jul 28 10:31:20] [opsiclientd]   Using host id 'vclientxphost.schulePN.local'     (opsiclientd.pyo|2546)
[5] [Jul 28 10:31:20] [opsiclientd]   Action processor name 'opsi/winst', version '4.8.4.0'     (opsiclientd.pyo|3080)
[4] [Jul 28 10:31:20] [opsiclientd]   Starting control pipe     (opsiclientd.pyo|2554)
[4] [Jul 28 10:31:20] [opsiclientd]   Control pipe started     (opsiclientd.pyo|2558)
[5] [Jul 28 10:31:20] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[4] [Jul 28 10:31:21] [opsiclientd]   Starting control server     (opsiclientd.pyo|2563)
[5] [Jul 28 10:31:21] [control server]   ControlServer initiated     (opsiclientd.pyo|1691)
[5] [Jul 28 10:31:21] [control server]   creating root resource     (opsiclientd.pyo|1696)
[4] [Jul 28 10:31:21] [opsiclientd]   Control server started     (opsiclientd.pyo|2572)
[4] [Jul 28 10:31:21] [opsiclientd]   Starting cache service     (opsiclientd.pyo|2577)
[4] [Jul 28 10:31:21] [control server]   Control server is accepting HTTPS requests on port 4441     (opsiclientd.pyo|1703)
[4] [Jul 28 10:31:21] [opsiclientd]   Cache service started     (opsiclientd.pyo|2581)
[4] [Jul 28 10:31:21] [opsiclientd]   Starting notification server     (opsiclientd.pyo|2586)
[5] [Jul 28 10:31:21] [opsiclientd]   cannot send notification 'subjectsChanged', no client connected     (Util.pyo|547)
[5] [Jul 28 10:31:21] [notification server]   Notification server starting     (Util.pyo|588)
[4] [Jul 28 10:31:21] [opsiclientd]   Notification server started     (opsiclientd.pyo|2604)
[5] [Jul 28 10:31:21] [opsiclientd]   
Event 'gui_startup' args:
{ 
"logoffCurrentUser" : false,
"actionNotifierCommand" : "C:\\Programme\\\\opsi.org\\\\preloginloader\\\\notifier.exe -p 4442 -s notifier\\\\action.ini",
"actionProcessorDesktop" : "current",
"lockWorkstation" : false,
"blockLogin" : true,
"syncConfig" : false,
"eventNotifierDesktop" : "current",
"eventNotifierCommand" : "C:\\Programme\\\\opsi.org\\\\preloginloader\\\\notifier.exe -p 4442 -s notifier\\\\event.ini",
"cacheMaxBandwidth" : 0,
"updateConfigFile" : true,
"actionProcessorCommand" : "%action_processor.command%",
"actionNotifierDesktop" : "current",
"updateActionProcessor" : true,
"getConfigFromService" : true,
"userCancelable" : false,
"useCachedConfig" : false,
"cacheProducts" : false,
"requiresCachedProducts" : false,
"message" : "Starting to process product actions. Attention: the computer may restart. Please save all unsaved data now.",
"writeLogToService" : true
}
     (opsiclientd.pyo|2466)
[4] [Jul 28 10:31:21] [opsiclientd]   gui startup event 'gui_startup' created     (opsiclientd.pyo|2482)
[4] [Jul 28 10:31:21] [opsiclientd]   Event 'daemon_shutdown' is deactivated     (opsiclientd.pyo|2397)
[4] [Jul 28 10:31:21] [opsiclientd]   Event 'daemon_startup' is deactivated     (opsiclientd.pyo|2397)
[4] [Jul 28 10:31:21] [opsiclientd]   Event 'vpn_startup' is deactivated     (opsiclientd.pyo|2397)
[5] [Jul 28 10:31:21] [event gui_startup]   Activating event '<event: gui_startup>'  (opsiclientd.pyo|239)
[4] [Jul 28 10:31:21] [opsiclientd]   Event '<event: gui_startup>' started     (opsiclientd.pyo|2490)
[4] [Jul 28 10:31:21] [opsiclientd]   Event '<event: panic>' started     (opsiclientd.pyo|2490)
[5] [Jul 28 10:31:21] [event panic]   Activating event '<event: panic>'  (opsiclientd.pyo|239)
[4] [Jul 28 10:31:21] [opsiclientd]   Waiting for gui startup (timeout: 120 seconds)     (opsiclientd.pyo|2615)
[5] [Jul 28 10:31:21] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:21] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:21] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:21] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:21] [control pipe]   Took 0.016s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:21] [opsiclientd]   Starting wait for GUI app     (opsiclientd.pyo|2507)
[5] [Jul 28 10:31:21] [opsiclientd]   Searching pids of process name winlogon.exe (only in session id: 0)     (Windows.pyo|517)
[5] [Jul 28 10:31:21] [opsiclientd]   Found process winlogon.exe with pid 428 in session 0     (Windows.pyo|535)
[4] [Jul 28 10:31:21] [opsiclientd]   Executing: C:\Programme\\opsi.org\\preloginloader\\notifier.exe -p 4442 -s notifier\\wait_for_gui.ini     (Windows.pyo|653)
[5] [Jul 28 10:31:21] [opsiclientd]   Process startet, pid: 1408     (Windows.pyo|655)
[5] [Jul 28 10:31:21] [event wait_for_gui]   Activating event '<event: wait_for_gui>'  (opsiclientd.pyo|239)
[5] [Jul 28 10:31:23] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:23] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:23] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:23] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:23] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:23] [notification server]   client connection made     (Util.pyo|459)
[5] [Jul 28 10:31:23] [notification server]   sending notification 'subjectsChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:25] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:25] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:25] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:25] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:25] [control pipe]   Took 0.015s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:27] [event gui_startup]   Searching pids of process name winlogon.exe (only in session id: None)  (Windows.pyo|517)
[5] [Jul 28 10:31:27] [event wait_for_gui]   Searching pids of process name winlogon.exe (only in session id: None)  (Windows.pyo|517)
[5] [Jul 28 10:31:27] [event gui_startup]   Found process winlogon.exe with pid 428 in session 0  (Windows.pyo|535)
[5] [Jul 28 10:31:27] [event wait_for_gui]   Found process winlogon.exe with pid 428 in session 0  (Windows.pyo|535)
[5] [Jul 28 10:31:27] [event gui_startup]   Process 'winlogon.exe' is running on activation of event <event: gui_startup> => firing  (opsiclientd.pyo|370)
[5] [Jul 28 10:31:27] [event wait_for_gui]   Process 'winlogon.exe' is running on activation of event <event: wait_for_gui> => firing  (opsiclientd.pyo|370)
[5] [Jul 28 10:31:27] [event gui_startup]   Event '<event: gui_startup>' occured  (opsiclientd.pyo|241)
[5] [Jul 28 10:31:27] [event wait_for_gui]   Event '<event: wait_for_gui>' occured  (opsiclientd.pyo|241)
[4] [Jul 28 10:31:27] [event gui_startup]   Firing event '<event: gui_startup>'  (opsiclientd.pyo|274)
[4] [Jul 28 10:31:27] [event wait_for_gui]   Firing event '<event: wait_for_gui>'  (opsiclientd.pyo|274)
[5] [Jul 28 10:31:27] [event gui_startup]   Event '<event: gui_startup>' now deactivated after 1 occurrences  (opsiclientd.pyo|246)
[4] [Jul 28 10:31:27] [opsiclientd]   Processing event <event: gui_startup>     (opsiclientd.pyo|2685)
[5] [Jul 28 10:31:27] [event wait_for_gui]   Event '<event: wait_for_gui>' now deactivated after 1 occurrences  (opsiclientd.pyo|246)
[5] [Jul 28 10:31:27]   Terminating wait for GUI app (pid 1408)     (opsiclientd.pyo|2524)
[5] [Jul 28 10:31:27] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[4] [Jul 28 10:31:27] [event processing]   ============= EventProcessingThread for event '<event: gui_startup>' started =============     (opsiclientd.pyo|1861)
[5] [Jul 28 10:31:27] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:27] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:27] [opsiclientd]   Searching pids of process name winlogon.exe (only in session id: 0)     (Windows.pyo|517)
[5] [Jul 28 10:31:27] [opsiclientd]   Found process winlogon.exe with pid 428 in session 0     (Windows.pyo|535)
[4] [Jul 28 10:31:27] [opsiclientd]   Executing: C:\Programme\\opsi.org\\preloginloader\\opsiclientd_rpc.exe "vclientxphost.schulePN.local" "*** confidential ***" "4441" "setCurrentActiveDesktopName(System.getActiveDesktopName())"     (Windows.pyo|653)
[5] [Jul 28 10:31:27] [opsiclientd]   Process startet, pid: 1720     (Windows.pyo|655)
[5] [Jul 28 10:31:27] [opsiclientd]   Waiting for process ending: 1720     (Windows.pyo|658)
[4] [Jul 28 10:31:27] [opsiclientd]   Gui started     (opsiclientd.pyo|2617)
[5] [Jul 28 10:31:27] [notification server]   client connection lost     (Util.pyo|464)
[5] [Jul 28 10:31:27] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:27] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:27] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:27] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:27] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:29] [control server]   ControlServerResourceJsonRpc: processing POST request     (opsiclientd.pyo|769)
[5] [Jul 28 10:31:29] [control server]   Got json-rpc request: {'params': [], 'id': 1, 'method': 'getPossibleMethods_listOfHashes'}     (opsiclientd.pyo|902)
[4] [Jul 28 10:31:29] [control server]   Authorization request from vclientxphost.schulePN.local@127.0.0.1     (opsiclientd.pyo|930)
[5] [Jul 28 10:31:29] [control server]   RPC method: 'getPossibleMethods_listOfHashes' params: '[]'     (opsiclientd.pyo|958)
[5] [Jul 28 10:31:29] [control server]   ControlServerResourceJsonRpc: processing POST request     (opsiclientd.pyo|769)
[5] [Jul 28 10:31:29] [control server]   Got json-rpc request: {'params': ['Winlogon'], 'id': 1, 'method': 'setCurrentActiveDesktopName'}     (opsiclientd.pyo|902)
[4] [Jul 28 10:31:29] [control server]   Authorization request from vclientxphost.schulePN.local@127.0.0.1     (opsiclientd.pyo|930)
[5] [Jul 28 10:31:29] [control server]   RPC method: 'setCurrentActiveDesktopName' params: '['Winlogon']'     (opsiclientd.pyo|958)
[4] [Jul 28 10:31:29] [opsiclientd]   rpc setCurrentActiveDesktopName: current active desktop name set to 'Winlogon'     (opsiclientd.pyo|2956)
[5] [Jul 28 10:31:29] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:29] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:29] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:29] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:29] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[4] [Jul 28 10:31:29] [opsiclientd]   Process ended: 1720     (Windows.pyo|661)
[4] [Jul 28 10:31:29] [event processing]   Starting notifier application in session '0' on desktop 'Winlogon'     (opsiclientd.pyo|1841)
[5] [Jul 28 10:31:29] [event processing]   Searching pids of process name winlogon.exe (only in session id: 0)     (Windows.pyo|517)
[5] [Jul 28 10:31:29] [event processing]   Found process winlogon.exe with pid 428 in session 0     (Windows.pyo|535)
[4] [Jul 28 10:31:29] [event processing]   Executing: C:\Programme\\opsi.org\\preloginloader\\notifier.exe -p 4442 -s notifier\\action.ini     (Windows.pyo|653)
[5] [Jul 28 10:31:29] [event processing]   Process startet, pid: 1756     (Windows.pyo|655)
[5] [Jul 28 10:31:29] [notification server]   client connection made     (Util.pyo|459)
[5] [Jul 28 10:31:29] [notification server]   sending notification 'subjectsChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:31] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:31] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:31] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:31] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:31] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[4] [Jul 28 10:31:32] [opsiclientd]   Getting config from service     (opsiclientd.pyo|2272)
[5] [Jul 28 10:31:32] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:32] [opsiclientd]   Starting ServiceConnectionThread, timeout is 10 seconds     (opsiclientd.pyo|2731)
[4] [Jul 28 10:31:32] [service connection]   Connecting to config server 'https://192.168.0.28:4447/rpc' #1     (opsiclientd.pyo|1780)
[5] [Jul 28 10:31:32] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:32] [service connection]   Opening https connection to 192.168.0.28:4447     (JSONRPC.pyo|172)
[5] [Jul 28 10:31:33] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:33] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:33] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:33] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:33] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:33] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:33] [service connection]   Successfully connected to '192.168.0.28:4447'     (JSONRPC.pyo|186)
[5] [Jul 28 10:31:34] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:35] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:35] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:35] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:35] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:35] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:35] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:36] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:37] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:37] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:37] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:37] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:37] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:37] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:38] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:38] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[4] [Jul 28 10:31:38] [service connection]   Connected to config server 'https://192.168.0.28:4447/rpc'     (opsiclientd.pyo|1792)
[5] [Jul 28 10:31:39] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:39] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:39] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:39] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:39] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'subjectsChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:39] [opsiclientd]   Updated config_service.host_id to 'opsiserver.schulepn.local'     (opsiclientd.pyo|2766)
[5] [Jul 28 10:31:39] [opsiclientd]   Verifying modules file signature     (opsiclientd.pyo|2781)
[5] [Jul 28 10:31:39] [opsiclientd]   Modules file signature verified     (opsiclientd.pyo|2796)
[5] [Jul 28 10:31:39] [opsiclientd]   Setting config value depot_server.drive     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'nextBootServiceURL'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'utilsUrl'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'configUrl'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'utilsDrive'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'opsiServer'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'nextBootServerType'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'depotUrl'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Setting config value depot_server.depot_id     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:39] [opsiclientd]   Setting config value depot_server.url     (opsiclientd.pyo|2151)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'configDrive'     (opsiclientd.pyo|2286)
[5] [Jul 28 10:31:39] [opsiclientd]   Unhandled network config key 'winDomain'     (opsiclientd.pyo|2286)
[4] [Jul 28 10:31:39] [opsiclientd]   Got network config from service     (opsiclientd.pyo|2288)
[4] [Jul 28 10:31:39] [opsiclientd]   Got config from service     (opsiclientd.pyo|2301)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[4] [Jul 28 10:31:39] [opsiclientd]   Trying to write config to file: 'C:\Programme\opsi.org\preloginloader\opsiclientd\opsiclientd.conf'     (opsiclientd.pyo|2232)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[4] [Jul 28 10:31:39] [opsiclientd]   Config file 'C:\Programme\opsi.org\preloginloader\opsiclientd\opsiclientd.conf' written     (opsiclientd.pyo|2260)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[1] [Jul 28 10:31:39] [opsiclientd]   Traceback:     (Logger.pyo|647)
[1] [Jul 28 10:31:39] [opsiclientd]        line 3103 in 'processProductActionRequests' in file 'opsiclientd.pyo'     (Logger.pyo|647)
[1] [Jul 28 10:31:39] [opsiclientd]        line 1 in 'getLocalBootProductStates_hash' in file '<string>'     (Logger.pyo|647)
[1] [Jul 28 10:31:39] [opsiclientd]        line 262 in '_jsonRPC' in file 'OPSI\Backend\JSONRPC.pyo'     (Logger.pyo|647)
[1] [Jul 28 10:31:39] [opsiclientd]        ==>>> 'vclientxphost.schulePN.local'     (opsiclientd.pyo|3168)
[2] [Jul 28 10:31:39] [opsiclientd]   Failed to process product action requests: 'vclientxphost.schulePN.local'     (opsiclientd.pyo|3169)
[5] [Jul 28 10:31:39] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[5] [Jul 28 10:31:41] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:41] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:41] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:41] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:41] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:42] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[2] [Jul 28 10:31:42] [opsiclientd]   Failed to get rebootRequested from registry: [Error 2] Das System kann die angegebene Datei nicht finden     (opsiclientd.pyo|3181)
[5] [Jul 28 10:31:42] [opsiclientd]   rebootRequested: 0     (opsiclientd.pyo|3182)
[5] [Jul 28 10:31:42] [opsiclientd]   shutdownRequested: 0     (opsiclientd.pyo|3200)
[4] [Jul 28 10:31:42] [opsiclientd]   Writing log to service     (opsiclientd.pyo|2316)
[5] [Jul 28 10:31:42] [notification server]   sending notification 'messageChanged' to clients     (Util.pyo|549)
[4] [Jul 28 10:31:42] [event processing]   Stopping notifier application     (opsiclientd.pyo|1850)
[5] [Jul 28 10:31:42] [opsiclientd]   Searching pids of process name winlogon.exe (only in session id: 0)     (Windows.pyo|517)
[5] [Jul 28 10:31:42] [opsiclientd]   Found process winlogon.exe with pid 428 in session 0     (Windows.pyo|535)
[4] [Jul 28 10:31:42] [opsiclientd]   Executing: C:\Programme\\opsi.org\\preloginloader\\opsiclientd_rpc.exe "vclientxphost.schulePN.local" "*** confidential ***" "4441" "exit(); System.closeProcessWindows(processId = 1756)"     (Windows.pyo|653)
[5] [Jul 28 10:31:42] [opsiclientd]   Process startet, pid: 1812     (Windows.pyo|655)
[5] [Jul 28 10:31:43] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:43] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:43] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:43] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:43] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:43] [control server]   ControlServerResourceJsonRpc: processing POST request     (opsiclientd.pyo|769)
[5] [Jul 28 10:31:43] [control server]   Got json-rpc request: {'params': [], 'id': 1, 'method': 'getPossibleMethods_listOfHashes'}     (opsiclientd.pyo|902)
[4] [Jul 28 10:31:43] [control server]   Authorization request from vclientxphost.schulePN.local@127.0.0.1     (opsiclientd.pyo|930)
[5] [Jul 28 10:31:43] [control server]   RPC method: 'getPossibleMethods_listOfHashes' params: '[]'     (opsiclientd.pyo|958)
[5] [Jul 28 10:31:45] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:45] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:45] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:45] [opsiclientd]   rpc getBlockLogin: blockLogin is 'True'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:45] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[4] [Jul 28 10:31:45] [event processing]   ============= EventProcessingThread for event '<event: gui_startup>' ended =============     (opsiclientd.pyo|1985)
[4] [Jul 28 10:31:45] [opsiclientd]   Done processing event '<event: gui_startup>'     (opsiclientd.pyo|2696)
[5] [Jul 28 10:31:45] [notification server]   client connection lost     (Util.pyo|464)
[5] [Jul 28 10:31:47] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:47] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:47] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:47] [opsiclientd]   rpc getBlockLogin: blockLogin is 'False'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:47] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)
[5] [Jul 28 10:31:51] [control pipe]   Received rpc from pipe '{"id": 1, "method": "getBlockLogin", "params": []}'     (opsiclientd.pyo|603)
[5] [Jul 28 10:31:51] [control pipe]   Creating pipe \\.\pipe\opsiclientd     (opsiclientd.pyo|644)
[5] [Jul 28 10:31:51] [control pipe]   RPC method: 'getBlockLogin' params: '[]'     (opsiclientd.pyo|502)
[4] [Jul 28 10:31:51] [opsiclientd]   rpc getBlockLogin: blockLogin is 'False'     (opsiclientd.pyo|2863)
[5] [Jul 28 10:31:51] [control pipe]   Took 0.000s to process getBlockLogin()     (opsiclientd.pyo|509)

Ich hoffe ja, dass ich Blindfisch irgendwas übersehen habe. Ansonsten ist die Installation sowohl des OPSI-Servers als auch der Pakete ganz exakt nach der - im übrigen hervorragenden! - Anleitung gelaufen.

[j.schneider]

Hallo,

das hilft schon mal weiter.
Können Sie bitte zusätzlich die opsiconfd-Log-Datei des Clients posten (/var/log/opsi/opsiconfd/<client>.log)?

[V. Sternemann]

Aber immer gerne doch!

Hier ist sie. Es müsste sich um den - hoffentlich sinnvollen - Ausschnitt des letzten Installationsversuches handeln. Die Datei ist inzwischen wesentlich zu lang, um sie in Gänze hier posten zu dürfen (max. 60.000 Zeichen).

Code: Alles auswählen

[4] [Jul 28 10:10:01] Client '192.168.0.31' did not send cookie (opsiconfd|261)
[4] [Jul 28 10:10:01] New session created (opsiconfd|951)
[4] [Jul 28 10:10:01] Authorization request from vclientxphost.schulePN.local@192.168.0.31 (opsiconfd|354)
[3] [Jul 28 10:10:06] Failed to resolve hostname 'vclientxphost.schulePN.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jul 28 10:10:06] Host login attempt with username 'vclientxphost.schulePN.local' from ip '192.168.0.31', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jul 28 10:10:06] BackendManager created. (opsiconfd|391)
[4] [Jul 28 10:10:07] Creating unix socket '/var/run/opsipxeconfd.socket' (OpsiPXEConfd.py|181)
[4] [Jul 28 10:10:24] Hardware config with config_id 8 vanished (table HARDWARE_CONFIG_COMPUTER_SYSTEM), updating audit_state (MySQL.py|1055)
[4] [Jul 28 10:12:45] New session created (opsiconfd|951)
[4] [Jul 28 10:12:47] Authorization request from vclientxphost.schulePN.local@192.168.0.31 (opsiconfd|354)
[3] [Jul 28 10:12:52] Failed to resolve hostname 'vclientxphost.schulePN.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jul 28 10:12:52] Host login attempt with username 'vclientxphost.schulePN.local' from ip '192.168.0.31', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jul 28 10:12:52] BackendManager created. (opsiconfd|391)
[4] [Jul 28 10:30:02] Client '192.168.0.31' did not send cookie (opsiconfd|261)
[4] [Jul 28 10:30:02] New session created (opsiconfd|951)
[4] [Jul 28 10:30:02] Authorization request from vclientxphost.schulePN.local@192.168.0.31 (opsiconfd|354)
[3] [Jul 28 10:30:07] Failed to resolve hostname 'vclientxphost.schulePN.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jul 28 10:30:07] Host login attempt with username 'vclientxphost.schulePN.local' from ip '192.168.0.31', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jul 28 10:30:07] BackendManager created. (opsiconfd|391)
[4] [Jul 28 10:30:33] Client '192.168.0.31' did not send cookie (opsiconfd|261)
[4] [Jul 28 10:30:33] New session created (opsiconfd|951)
[4] [Jul 28 10:30:33] Authorization request from vclientxphost.schulePN.local@192.168.0.31 (opsiconfd|354)
[3] [Jul 28 10:30:38] Failed to resolve hostname 'vclientxphost.schulePN.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jul 28 10:30:38] Host login attempt with username 'vclientxphost.schulePN.local' from ip '192.168.0.31', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jul 28 10:30:38] BackendManager created. (opsiconfd|391)
[1] [Jul 28 10:30:38] Traceback (most recent call last):
  File "/usr/lib/python2.5/threading.py", line 446, in run
    self.__target(*self.__args, **self.__kwargs)
  File "/usr/lib/python2.5/site-packages/twisted/python/threadpool.py", line 161, in _worker
    context.call(ctx, function, *args, **kwargs)
  File "/usr/lib/python2.5/site-packages/twisted/python/context.py", line 59, in callWithContext
    return self.currentContext().callWithContext(ctx, func, *args, **kw)
  File "/usr/lib/python2.5/site-packages/twisted/python/context.py", line 37, in callWithContext
    return func(*args,**kw)
--- <exception caught here> ---
  File "/usr/lib/python2.5/site-packages/twisted/internet/threads.py", line 24, in _putResultInDeferred
    result = f(*args, **kwargs)
  File "/usr/sbin/opsiconfd", line 528, in _realRpc
    self.result['result'] = eval( "bm.%s(%s)" % (method, str(params)[1:-1]) )
  File "<string>", line 1, in <module>
    
  File "/etc/opsi/backendManager.d/50_interface.conf", line 1213, in setProductInstallationStatus
    return self._execMethod(self.defaultBackend, 'setProductInstallationStatus', productId, objectId, installationStatus)
  File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 499, in _execMethod
    res = eval( "instance.%s%s" % (method, params) )
  File "<string>", line 1, in <module>
    
  File "/var/lib/python-support/python2.5/OPSI/Backend/File31.py", line 2037, in setProductInstallationStatus
    self.setProductState(productId, objectId, installationStatus = installationStatus)
  File "/var/lib/python-support/python2.5/OPSI/Backend/File31.py", line 1950, in setProductState
    productType = self.getProductType(productId = productId, depotId = depotId)
  File "/var/lib/python-support/python2.5/OPSI/Backend/Backend.py", line 347, in getProductType
    raise Exception("product '%s': is neither localboot nor netboot product" % productId)
exceptions.Exception: product 'preloginvista': is neither localboot nor netboot product
 (opsiconfd|620)
[2] [Jul 28 10:30:38] Failed to process rpc: product 'preloginvista': is neither localboot nor netboot product (opsiconfd|624)
[4] [Jul 28 10:31:45] Client '192.168.0.31' did not send cookie (opsiconfd|261)
[4] [Jul 28 10:31:45] New session created (opsiconfd|951)
[4] [Jul 28 10:31:45] Authorization request from vclientxphost.schulePN.local@192.168.0.31 (opsiconfd|354)
[3] [Jul 28 10:31:50] Failed to resolve hostname 'vclientxphost.schulePN.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jul 28 10:31:50] Host login attempt with username 'vclientxphost.schulePN.local' from ip '192.168.0.31', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jul 28 10:31:51] BackendManager created. (opsiconfd|391)
[1] [Jul 28 10:31:52] Traceback (most recent call last):
  File "/usr/lib/python2.5/threading.py", line 446, in run
    self.__target(*self.__args, **self.__kwargs)
  File "/usr/lib/python2.5/site-packages/twisted/python/threadpool.py", line 161, in _worker
    context.call(ctx, function, *args, **kwargs)
  File "/usr/lib/python2.5/site-packages/twisted/python/context.py", line 59, in callWithContext
    return self.currentContext().callWithContext(ctx, func, *args, **kw)
  File "/usr/lib/python2.5/site-packages/twisted/python/context.py", line 37, in callWithContext
    return func(*args,**kw)
--- <exception caught here> ---
  File "/usr/lib/python2.5/site-packages/twisted/internet/threads.py", line 24, in _putResultInDeferred
    result = f(*args, **kwargs)
  File "/usr/sbin/opsiconfd", line 528, in _realRpc
    self.result['result'] = eval( "bm.%s(%s)" % (method, str(params)[1:-1]) )
  File "<string>", line 1, in <module>
    
  File "/etc/opsi/backendManager.d/50_interface.conf", line 1340, in getLocalBootProductStates_hash
    return self.adjustProductStates( productStates, objectIds, options )
  File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 1003, in adjustProductStates
    for productState in allProductStates[hostId]:
exceptions.KeyError: 'vclientxphost.schulePN.local'
 (opsiconfd|620)
[2] [Jul 28 10:31:52] Failed to process rpc: 'vclientxphost.schulePN.local' (opsiconfd|624)

[V. Sternemann]

Muss noch ergänzen: die scheinbaren DNS-Probleme sind gelöst. Wie die sich eingeschlichen haben, ist mir unklar. Aber die Zuordnung von IP-Adressen und Namen ist (jetzt) korrekt und auch die Einbindung in die Domäne. Die waren anscheinend NICHT der Grund für die Verweigerung weiterer Installationsvorgänge.

Was mich vor allem noch stutzig macht, ist dieser "Keyerror", der immer wieder von Python im Script "BackendManager.py" gemeldet wird. Vielleicht auch ein Grund, warum die Logs im Backend vom Opsi Configuration Editor gar nicht erst ankommen?

Fragen über Fragen

Hoffe, man kann mir da noch irgendwie helfen. Also im Handbuch steht sonst nix mehr dazu und das war dort drin steht, habe ich nach bestem Wissen und Gewissen umgesetzt.

[V. Sternemann]

Und noch eine Ergänzung: Ich habe jetzt die neue Engine, die für die Installationsschritte zuständig ist, mal in der entsprechenden Konfigurationsdatei abgeschaltet und habe anstelle dessen die alte Engine benutzt. Die läuft lustigerweise mit sonst exakt dem gleichen Setup der Netzwerkumgebung blendend.

Mir kommt es ein bisschen so vor als sei die V3.3.1 irgendwie noch eine Ecke stabiler. Klar sind die neuen Features von OPSI schon super und mit der V3.3.x eben nicht machbar. Aber trotzdem scheint mir die V 3.4 einfach noch nicht ganz "fertig" zu sein. Kann das sein oder irre ich mich? Soll aber keine bös gemeinte Kritik sein. Die Features der V3.4 sind schon wirklich klasse. Ich würde sie von daher lieber einsetzen (und mich im Rahmen meiner Tätigkeiten auch gerne an der kommerziellen Lizenz beteiligen), allerdings bin ich mir nicht sicher, ob sich das (schon) lohnt oder ob man dann mit dem Deployment von Vista und Win7 nicht doch lieber noch etwas warten sollte... Oder?

[d.oertel]

Hi,

ich habe das noch nicht alles analysiert.
Aber mir ist aufgefallen das das produkt preloginvista verwendet wird.
Dieses nicht verwenden.
Die OS-Produkte für 3.4 und den preloginloader 3.4 verwenden.
Preloginvista am besten vom Server entfernen.

Hilft das

d. oertel

[j.schneider]

Hallo,

ja opsi 3.4 ist noch nicht ganz fertig, sobald wir opsi 3.4 als Stable betrachten, werden wir die offizielle Freigabemitteilung rausschicken.
Das Problem wird von dem Domainnamen schulePN.local verursacht. Opsi hat hier ein Problem mit der Groß-/Kleinschreibung.
Soeben habe ich eine neue Version von python-opsi in unser Debian-Repository hochgeladen.
Ich hoffe das Problem tritt mit der neuen Version nicht mehr auf.
Bitte Updaten, Testen und Rückmeldung geben.
Danke!

Gruß
Jan Schneider

[V. Sternemann]

Hallo und lieben Dank schon mal an Sie beide, Herr Oertel und Herr Schneider.

Ich war heute noch nicht dazu gekommen, irgendwas zu testen. Gott sei Dank, denn sonst hätte ich den schönen neuen OPSI erstmal wieder neu aufgesetzt, aber hier gibt es ja doch gute Ansätze und die ganze Arbeit kann ich mir sparen! Sie glauben nicht, was das gerade für eine Freude bei mir produziert^^

Ich melde mich, sobald ich die beiden Ansätze ausprobiert habe.