Windows 7 no product action requests set

[cshields]

With a fresh OPSI 3.4 install (windows vista/7 license module included), I can not get any software products to install to a Win7 client. The client connects fine and quickly says that "No product action requests set". On the OPSI side, the product shows as being installed to the current version (though no dependencies are ever touched).

Hope some of the logs below can help. I'm stumped with this one:

opsiconfd:

[4] [Jan 17 14:37:56] Client '172.16.201.157' did not send cookie (opsiconfd|261)
[4] [Jan 17 14:37:56] New session created (opsiconfd|950)
[4] [Jan 17 14:37:57] Authorization request from vmware-win7.shields.local@172.16.201.157 (opsiconfd|354)
[3] [Jan 17 14:38:02] Failed to resolve hostname 'vmware-win7.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 17 14:38:02] Host login attempt with username 'vmware-win7.shields.local' from ip '172.16.201.157', but name resolves to '[]', ip verific
ation is disabled (access granted) (opsiconfd|384)
[4] [Jan 17 14:38:02] BackendManager created. (opsiconfd|391)

opsiclientd:

[3] [Jan 17 10:55:12] [opsiclientd] Refusing to set empty value for config value 'block_notifier_command' of section 'opsiclientd_notifier' (opsiclientd.pyo|2161)
[0] [Jan 17 10:55:12] [opsiclientd] Opsiclientd version: 0.5.7.4 (opsiclientd.pyo|2549)
[0] [Jan 17 10:55:12] [opsiclientd] Commandline: C:\Program Files\opsi.org\preloginloader\opsiclientd.exe (opsiclientd.pyo|2550)
[0] [Jan 17 10:55:12] [opsiclientd] Working directory: C:\Windows\system32 (opsiclientd.pyo|2551)
[2] [Jan 17 10:55:54] [opsiclientd] Failed to get rebootRequested from registry: [Error 2] The system cannot find the file specified (opsiclientd.pyo|3188)

Nothing more is found in either log. Any thoughts? Has Win7 worked for anyone yet?

Cheers,
-C

[wolfbardo]

Hi,

if you start testing opsi with Windows 7 check the packages in http://download.uib.de/opsi3.4/testing/

(see viewtopic.php?f=10&t=1129)

regards

Bardo Wolf

[cshields]

(I posted a reply yesterday to this but it is not showing today - ugh.. hope this one goes through)

Hi Bardo, thanks for replying.

I am running with the latest packages from the testing directory. Updated them again last night just to be sure and still got the same behavior and same log output. I've tried this against a new opsi 3.4 VM install (with updated packages) as well as a fresh Win7 enterprise client VM. The client was installed by hand, not by OPSI. I've also tried with an older OPSI VM that was upgraded to 3.4 and testing packages with no success. Likewise, I've tried another Win7 VM (one that I use frequently) and had the same issue against both OPSI servers.

preloginloader runs and contacts the server, but thinks that there is nothing to be done. The server, on the other hand, thinks that the software was correctly installed.

TIA,
-Corey

[d.oertel]

Hi,

please post the entries from the log tab of the opsi-configed (clientconnect, instlog,opsiconfd)

regrads

d.oertel

[cshields]

Sure! I have entries for clientconnect and opsiconfd. The others were blank.

clientconnect:

[0] [Jan 25 21:55:02] [opsiclientd] Opsiclientd version: 0.7.8 (opsiclientd.pyo|3723)
[0] [Jan 25 21:55:02] [opsiclientd] Commandline: C:\Program Files\opsi.org\preloginloader\opsiclientd.exe (opsiclientd.pyo|3724)
[0] [Jan 25 21:55:02] [opsiclientd] Working directory: C:\Windows\system32 (opsiclientd.pyo|3725)
[2] [Jan 25 21:55:31] [event processing gui_startup] C:\Program Files\opsi.org\preloginloader\lib\library.zip\opsiclientd.py DeprecationWarning: getPublicKeyObject is deprecated since Twisted Conch 0.9. Use Key.fromString().
(warnings.pyo|133)
[3] [Jan 25 21:55:35] [opsiclientd] Failed to get rebootRequested from registry: [Error 2] The system cannot find the file specified (opsiclientd.pyo|3906)
-------------------- submitted part of log file ends here, see the rest of log file on client --------------------

opsiconfd:

[4] [Jan 20 01:37:07] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:37:07] New session created (opsiconfd|950)
[4] [Jan 20 01:37:07] Authorization request from none@172.16.201.129 (opsiconfd|354)
[2] [Jan 20 01:37:09] Forbidden: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10) (opsiconfd|417)
[1] [Jan 20 01:37:09] Traceback (most recent call last):
File "/usr/sbin/opsiconfd", line 118, in http_GET
return worker.process()
File "/usr/sbin/opsiconfd", line 200, in process
self.deferred.callback(None)
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 243, in callback
self._startRunCallbacks(result)
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 312, in _startRunCallbacks
self._runCallbacks()
--- <exception caught here> ---
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 328, in _runCallbacks
self.result = callback(self.result, *args, **kw)
File "/usr/sbin/opsiconfd", line 389, in _authenticate
configFile = backendManagerConf )
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 169, in __init__
self._authenticate(self.__username, self.__password)
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 286, in _authenticate
return self._pamAuthenticate(user, password)
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 380, in _pamAuthenticate
raise BackendAuthenticationError("PAM authentication failed for user '%s': %s" % (user, e))
OPSI.Backend.Backend.BackendAuthenticationError: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10)
(opsiconfd|619)
[2] [Jan 20 01:37:09] Failed to process rpc: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10) (opsiconfd|623)
[4] [Jan 20 01:37:16] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:37:16] New session created (opsiconfd|950)
[4] [Jan 20 01:37:16] Authorization request from root@172.16.201.129 (opsiconfd|354)
[4] [Jan 20 01:37:16] BackendManager created. (opsiconfd|391)
[3] [Jan 20 01:37:50] No hardware info for host 'win-itsc9cmgkf1.shields.local' found: [Errno 2] No such file or directory: '/var/lib/opsi/audit/win-itsc9cmgkf1.shields.local.hw' (File31.py|809)
[3] [Jan 20 01:37:50] No software info for host 'win-itsc9cmgkf1.shields.local' found: [Errno 2] No such file or directory: '/var/lib/opsi/audit/win-itsc9cmgkf1.shields.local.sw' (File31.py|750)
[4] [Jan 20 01:37:52] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:37:52] New session created (opsiconfd|950)
[4] [Jan 20 01:37:52] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 01:37:56] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 01:37:56] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 01:37:56] BackendManager created. (opsiconfd|391)
[4] [Jan 20 01:56:04] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:56:04] New session created (opsiconfd|950)
[4] [Jan 20 01:56:04] Authorization request from none@172.16.201.129 (opsiconfd|354)
[2] [Jan 20 01:56:06] Forbidden: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10) (opsiconfd|417)
[1] [Jan 20 01:56:06] Traceback (most recent call last):
File "/usr/sbin/opsiconfd", line 118, in http_GET
return worker.process()
File "/usr/sbin/opsiconfd", line 200, in process
self.deferred.callback(None)
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 243, in callback
self._startRunCallbacks(result)
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 312, in _startRunCallbacks
self._runCallbacks()
--- <exception caught here> ---
File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 328, in _runCallbacks
self.result = callback(self.result, *args, **kw)
File "/usr/sbin/opsiconfd", line 389, in _authenticate
configFile = backendManagerConf )
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 169, in __init__
self._authenticate(self.__username, self.__password)
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 286, in _authenticate
return self._pamAuthenticate(user, password)
File "/var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py", line 380, in _pamAuthenticate
raise BackendAuthenticationError("PAM authentication failed for user '%s': %s" % (user, e))
OPSI.Backend.Backend.BackendAuthenticationError: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10)
(opsiconfd|619)
[2] [Jan 20 01:56:06] Failed to process rpc: PAM authentication failed for user 'none': ('User not known to the underlying authentication module', 10) (opsiconfd|623)
[4] [Jan 20 01:58:53] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:58:53] New session created (opsiconfd|950)
[4] [Jan 20 01:58:53] Authorization request from root@172.16.201.129 (opsiconfd|354)
[4] [Jan 20 01:58:53] BackendManager created. (opsiconfd|391)
[4] [Jan 20 01:59:09] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 01:59:09] New session created (opsiconfd|950)
[4] [Jan 20 01:59:09] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 01:59:13] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 01:59:13] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 01:59:13] BackendManager created. (opsiconfd|391)
[4] [Jan 20 02:01:12] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 02:01:12] New session created (opsiconfd|950)
[4] [Jan 20 02:01:12] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 02:01:16] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 02:01:16] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 02:01:16] BackendManager created. (opsiconfd|391)
[4] [Jan 20 02:01:20] Session 'bYbYhWfoTv7RKAbGodizyzYBuwNqI6cQ' deleted (opsiconfd|984)
[4] [Jan 20 02:03:23] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 02:03:23] New session created (opsiconfd|950)
[4] [Jan 20 02:03:23] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 02:03:27] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 02:03:27] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 02:03:27] BackendManager created. (opsiconfd|391)
[4] [Jan 20 02:03:31] Session 'lrilfxK0rEU4u9tMUMkvERDU6wVc2tym' deleted (opsiconfd|984)
[4] [Jan 20 02:05:07] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 02:05:07] New session created (opsiconfd|950)
[4] [Jan 20 02:05:08] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 02:05:12] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 02:05:12] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 02:05:12] BackendManager created. (opsiconfd|391)
[4] [Jan 20 02:05:16] Session 'ZvE7aDPLCEy3Tl88DHhHtQmltvLYGBwB' deleted (opsiconfd|984)
[4] [Jan 20 02:11:47] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 02:11:47] New session created (opsiconfd|950)
[4] [Jan 20 02:11:47] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 02:11:52] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 02:11:52] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 02:11:52] BackendManager created. (opsiconfd|391)
[2] [Jan 20 02:11:52] /var/lib/python-support/python2.5/OPSI/Backend/BackendManager.py DeprecationWarning: getPublicKeyObject is deprecated since Twisted Conch 0.9. Use Key.fromString().
publicKey = keys.getPublicKeyObject(data = base64.decodestring('AAAAB3NzaC1yc2EAAAADAQABAAABAQCAD/I79Jd0eKwwfuVwh5B2z+S8aV0C5suItJa18RrYip+d4P0ogzqoCfOoVWtDojY96FDYv+2d73LsoOckHCnuh55GA0mtuVMWdXNZIE8Avt/RzbEoYGo/H0weuga7I8PuQNC/nyS8w3W8TH4pt+ZCjZZoX8S+IizWCYwfqYoYTMLgB0i+6TCAfJj3mNgCrDZkQ24+rOFS4a8RrjamEz/b81noWl9IntllK1hySkR+LbulfTGALHgHkDUlk0OSu+zBPw/hcDSOMiDQvvHfmR4quGyLPbQ2FOVm1TzE0bQPR+Bhx4V8Eo2kNYstG2eJELrz7J1TJI0rCjpB+FQjYPsP'))
(warnings.py|133)
[4] [Jan 20 02:11:55] Session 'DimxzRxgyIhnmtGosrWzKeGB3DeKCvRf' deleted (opsiconfd|984)
[4] [Jan 20 02:14:13] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 20 02:14:13] New session created (opsiconfd|950)
[4] [Jan 20 02:14:13] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 20 02:14:17] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 20 02:14:17] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 20 02:14:17] BackendManager created. (opsiconfd|391)
[4] [Jan 20 02:14:21] Session 'V0YRVFd6MtBQKngYjy9gFqRe0OkMFhOg' deleted (opsiconfd|984)

[d.oertel]

Hi,

ok - we need to look closer ( the clientconnect was from 25.1. - the opsiconfd from 20.1.)
Please go to the client and set at the opsiclientd.conf
(%Proramfiles%\opsi.org\preloginloader\opsiclientd\opsiclientd.conf)
In section [global] log_level = 5
set any product to setup
reboot
post from c:\tmp
opsiclientd.log
instlog.txt
and from the server
/var/log/opsi/opsiconfd/<clientname>.log

regards

d.oertel

[cshields]

(tried installing the swaudit product, though any and all products I have tried all fail)

Here are the detailed logs. I haven't read through them yet, I'll dig through them deeper after I post this:

opsiclientd.log
[5] [Jan 28 19:58:55] [opsiclientd] Now using log-file 'c:\\tmp\\opsiclientd.log' (Logger.pyo|335)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value action_processor.command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value action_processor.local_dir (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value action_processor.remote_dir (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value action_processor.run_as_user (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value action_processor.filename (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.update_action_processor (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.cache_products (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.use_cached_config (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.requires_cached_products (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.block_login (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.user_cancelable (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.cache_max_bandwidth (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.lock_workstation (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.sync_config (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_processor_timeout (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.process_shutdown_requests (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.message (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.active (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.update_config_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.event_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.get_config_from_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.logoff_current_user (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_processor_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.event_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.action_processor_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.notification_delay (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_user_login.write_log_to_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_daemon_shutdown.active (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_daemon_shutdown.type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value notification_server.interface (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value notification_server.start_port (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value notification_server.port (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.log_level (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.wait_for_gui_timeout (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.opsi_host_key (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.wait_for_gui_application (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.host_id (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.wait_before_reboot (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.wait_before_shutdown (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value global.log_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Now using log-file 'c:\\tmp\\opsiclientd.log' (Logger.pyo|335)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value control_server.ssl_server_cert_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value control_server.static_dir (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value control_server.ssl_server_key_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value control_server.interface (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value control_server.port (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value opsiclientd_rpc.command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value depot_server.url (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value depot_server.username (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value depot_server.drive (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value depot_server.depot_id (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_daemon_startup.active (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_daemon_startup.type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.cache_products (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.use_cached_config (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.requires_cached_products (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.block_login (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.user_cancelable (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.cache_max_bandwidth (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.lock_workstation (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.sync_config (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.message (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.write_log_to_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.update_config_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.event_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.get_config_from_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.logoff_current_user (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.action_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.action_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.action_processor_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.event_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.action_processor_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_gui_startup.update_action_processor (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value opsiclientd_notifier.command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.cache_products (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.write_log_to_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.cache_max_bandwidth (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.wql (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.active (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.message (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.update_config_file (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.event_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.get_config_from_service (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.action_notifier_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.warning_time (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.service_options (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.event_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.action_processor_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.action_processor_command (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.type (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value event_vpn_startup.action_notifier_desktop (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value cache_service.storage_dir (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value cache_service.backend_manager_config (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value config_service.url (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value config_service.server_id (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value config_service.user_cancellable_after (opsiclientd.pyo|3368)
[5] [Jan 28 19:58:55] [opsiclientd] Setting config value config_service.connection_timeout (opsiclientd.pyo|3368)
[4] [Jan 28 19:58:55] [opsiclientd] Config read (opsiclientd.pyo|3456)
[0] [Jan 28 19:58:55] [opsiclientd] Opsiclientd version: 0.7.8 (opsiclientd.pyo|3723)
[0] [Jan 28 19:58:55] [opsiclientd] Commandline: C:\Program Files\opsi.org\preloginloader\opsiclientd.exe (opsiclientd.pyo|3724)
[0] [Jan 28 19:58:55] [opsiclientd] Working directory: C:\Windows\system32 (opsiclientd.pyo|3725)
[4] [Jan 28 19:58:55] [opsiclientd] Using host id 'win-itsc9cmgkf1.shields.local' (opsiclientd.pyo|3726)
[4] [Jan 28 19:58:55] [opsiclientd] Block login now set to 'True' (opsiclientd.pyo|3335)
[4] [Jan 28 19:58:55] [opsiclientd] Starting control pipe (opsiclientd.pyo|3730)
[5] [Jan 28 19:58:55] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[4] [Jan 28 19:58:55] [opsiclientd] Control pipe started (opsiclientd.pyo|3734)
[4] [Jan 28 19:58:55] [opsiclientd] Starting control server (opsiclientd.pyo|3739)
[5] [Jan 28 19:58:55] [control server] ControlServer initiated (opsiclientd.pyo|2056)
[5] [Jan 28 19:58:55] [control server] creating root resource (opsiclientd.pyo|2061)
[4] [Jan 28 19:58:55] [control server] Control server is accepting HTTPS requests on port 4441 (opsiclientd.pyo|2068)
[4] [Jan 28 19:58:55] [opsiclientd] Control server started (opsiclientd.pyo|3748)
[4] [Jan 28 19:58:55] [opsiclientd] Starting cache service (opsiclientd.pyo|3753)
[4] [Jan 28 19:58:55] [opsiclientd] Cache service started (opsiclientd.pyo|3757)
[4] [Jan 28 19:58:55] [opsiclientd] Event config 'user_login' is deactivated (opsiclientd.pyo|3566)
[4] [Jan 28 19:58:55] [opsiclientd] Event config 'daemon_shutdown' is deactivated (opsiclientd.pyo|3566)
[4] [Jan 28 19:58:55] [opsiclientd] Event config 'daemon_startup' is deactivated (opsiclientd.pyo|3566)
[4] [Jan 28 19:58:55] [opsiclientd] Event config 'vpn_startup' is deactivated (opsiclientd.pyo|3566)
[5] [Jan 28 19:58:55] [opsiclientd]
Event config 'gui_startup' args:
{
"logoffCurrentUser" : false,
"actionNotifierCommand" : "C:\\Program Files\\\\opsi.org\\\\preloginloader\\\\notifier.exe -p %port% -s notifier\\\\action.ini",
"actionProcessorDesktop" : "current",
"lockWorkstation" : false,
"blockLogin" : true,
"syncConfig" : false,
"eventNotifierDesktop" : "current",
"eventNotifierCommand" : "C:\\Program Files\\\\opsi.org\\\\preloginloader\\\\notifier.exe -p %port% -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|3645)
[4] [Jan 28 19:58:55] [opsiclientd] gui startup event generator 'gui_startup' created (opsiclientd.pyo|3650)
[5] [Jan 28 19:58:55] [event generator gui_startup] Initializing event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' (opsiclientd.pyo|615)
[5] [Jan 28 19:58:55] [event generator gui_startup] Need to import wmi / pythoncom (opsiclientd.pyo|268)
[4] [Jan 28 19:58:55] [opsiclientd] Event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' started (opsiclientd.pyo|3658)
[5] [Jan 28 19:58:55] [event generator panic] Initializing event generator '<PanicEventGenerator(Thread-7, started daemon)>' (opsiclientd.pyo|615)
[5] [Jan 28 19:58:55] [event generator panic] Activating event generator '<PanicEventGenerator(Thread-7, started daemon)>' (opsiclientd.pyo|623)
[5] [Jan 28 19:58:55] [event generator panic] Getting next event... (opsiclientd.pyo|625)
[4] [Jan 28 19:58:55] [opsiclientd] Event generator '<PanicEventGenerator(Thread-7, started daemon)>' started (opsiclientd.pyo|3658)
[4] [Jan 28 19:58:55] [opsiclientd] Waiting for gui startup (timeout: 120 seconds) (opsiclientd.pyo|3768)
[5] [Jan 28 19:58:55] [opsiclientd] Starting wait for GUI app (opsiclientd.pyo|3675)
[5] [Jan 28 19:58:55] [opsiclientd] Searching pids of process name winlogon.exe (session id: 1) (Windows.pyo|565)
[5] [Jan 28 19:58:55] [opsiclientd] Found process winlogon.exe with matching name (pid 464, session 1) (Windows.pyo|587)
[4] [Jan 28 19:58:55] [opsiclientd] Executing: 'C:\Program Files\\opsi.org\\preloginloader\\notifier.exe -s notifier\\wait_for_gui.ini' in session '1' on desktop 'winsta0\winlogon' (Windows.pyo|716)
[5] [Jan 28 19:58:55] [opsiclientd] Process startet, pid: 1512 (Windows.pyo|718)
[5] [Jan 28 19:58:55] [event generator wait_for_gui] Initializing event generator '<GUIStartupEventGenerator(Thread-9, started daemon)>' (opsiclientd.pyo|615)
[5] [Jan 28 19:58:55] [event generator wait_for_gui] Need to import wmi / pythoncom (opsiclientd.pyo|268)
[4] [Jan 28 19:58:56] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:58:56] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:58:56] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:58:56] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[4] [Jan 28 19:59:01] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:01] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:01] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:01] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[4] [Jan 28 19:59:06] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:06] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:06] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:06] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[5] [Jan 28 19:59:11] [event generator gui_startup] Watching for wql: SELECT * FROM __InstanceCreationEvent WITHIN 1 WHERE TargetInstance ISA 'Win32_Process' AND TargetInstance.Name = 'LogonUI.exe' (opsiclientd.pyo|681)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Watching for wql: SELECT * FROM __InstanceCreationEvent WITHIN 1 WHERE TargetInstance ISA 'Win32_Process' AND TargetInstance.Name = 'LogonUI.exe' (opsiclientd.pyo|681)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Activating event generator '<GUIStartupEventGenerator(Thread-9, started daemon)>' (opsiclientd.pyo|623)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Getting next event... (opsiclientd.pyo|625)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Getting first event, checking if process 'LogonUI.exe' already running (opsiclientd.pyo|736)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Searching pids of process name LogonUI.exe (session id: None) (Windows.pyo|565)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Found process logonui.exe with matching name (pid 816, session 1) (Windows.pyo|587)
[4] [Jan 28 19:59:11] [event generator wait_for_gui] Process 'LogonUI.exe' is running (opsiclientd.pyo|738)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Firing event '<opsiclientd.GUIStartupEvent object at 0x01921610>' (opsiclientd.pyo|585)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Event info: (opsiclientd.pyo|586)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Starting FireEventThread for listeners: [<opsiclientd.WaitForGUI object at 0x00E93E90>] (opsiclientd.pyo|608)
[5] [Jan 28 19:59:11] [event generator gui_startup] Activating event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' (opsiclientd.pyo|623)
[5] [Jan 28 19:59:11] [event generator gui_startup] Getting next event... (opsiclientd.pyo|625)
[5] [Jan 28 19:59:11] [event generator gui_startup] Getting first event, checking if process 'LogonUI.exe' already running (opsiclientd.pyo|736)
[5] [Jan 28 19:59:11] [event generator gui_startup] Searching pids of process name LogonUI.exe (session id: None) (Windows.pyo|565)
[5] [Jan 28 19:59:11] [event generator gui_startup] Found process logonui.exe with matching name (pid 816, session 1) (Windows.pyo|587)
[4] [Jan 28 19:59:11] [event generator gui_startup] Process 'LogonUI.exe' is running (opsiclientd.pyo|738)
[5] [Jan 28 19:59:11] [event generator gui_startup] Firing event '<opsiclientd.GUIStartupEvent object at 0x01921810>' (opsiclientd.pyo|585)
[5] [Jan 28 19:59:11] [event generator gui_startup] Event info: (opsiclientd.pyo|586)
[5] [Jan 28 19:59:11] [event generator gui_startup] Starting FireEventThread for listeners: [<OpsiclientdNT61(Thread-1, started daemon)>] (opsiclientd.pyo|608)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Event generator '<GUIStartupEventGenerator(Thread-9, started daemon)>' now deactivated after 1 event occurrences (opsiclientd.pyo|630)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Calling processEvent on listener <opsiclientd.WaitForGUI object at 0x00E93E90> (opsiclientd.pyo|603)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Event generator '<GUIStartupEventGenerator(Thread-9, started daemon)>' cleaning up in 10 seconds (opsiclientd.pyo|712)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] GUI started (opsiclientd.pyo|3701)
[5] [Jan 28 19:59:11] [event generator wait_for_gui] Terminating wait for GUI app (pid 1512) (opsiclientd.pyo|3704)
[5] [Jan 28 19:59:11] [event generator gui_startup] Calling processEvent on listener <OpsiclientdNT61(Thread-1, started daemon)> (opsiclientd.pyo|603)
[4] [Jan 28 19:59:11] [opsiclientd] Processing event <opsiclientd.GUIStartupEvent object at 0x01921810> (opsiclientd.pyo|3812)
[5] [Jan 28 19:59:11] [event processing gui_startup] Using active console session id (opsiclientd.pyo|2250)
[5] [Jan 28 19:59:11] [event processing gui_startup] Session id set to 1 (opsiclientd.pyo|2238)
[5] [Jan 28 19:59:11] [event generator gui_startup] Event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' now deactivated after 1 event occurrences (opsiclientd.pyo|630)
[5] [Jan 28 19:59:11] [event generator gui_startup] Event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' cleaning up in 10 seconds (opsiclientd.pyo|712)
[4] [Jan 28 19:59:11] [event processing gui_startup] ============= EventProcessingThread for occurcence of event '<opsiclientd.GUIStartupEvent object at 0x01921810>' started ============= (opsiclientd.pyo|2922)
[4] [Jan 28 19:59:11] [event processing gui_startup] Starting notification server on port 44001 (opsiclientd.pyo|2259)
[5] [Jan 28 19:59:11] [event processing gui_startup] cannot send notification 'subjectsChanged', no client connected (Util.pyo|548)
[4] [Jan 28 19:59:11] [opsiclientd] Gui started (opsiclientd.pyo|3770)
[5] [Jan 28 19:59:11] Notification server starting (Util.pyo|594)
[4] [Jan 28 19:59:11] [event processing gui_startup] Notification server started (opsiclientd.pyo|2277)
[5] [Jan 28 19:59:11] [event processing gui_startup] Action processor name 'opsi/winst', version '4.9.3.0' (opsiclientd.pyo|2538)
[5] [Jan 28 19:59:11] [event processing gui_startup] cannot send notification 'messageChanged', no client connected (Util.pyo|548)
[5] [Jan 28 19:59:11] [event processing gui_startup] cannot send notification 'messageChanged', no client connected (Util.pyo|548)
[5] [Jan 28 19:59:11] [event processing gui_startup] cannot send notification 'messageChanged', no client connected (Util.pyo|548)
[4] [Jan 28 19:59:11] [opsiclientd] Block login now set to 'True' (opsiclientd.pyo|3335)
[4] [Jan 28 19:59:11] [event processing gui_startup] Starting notifier application in session '1' (opsiclientd.pyo|2499)
[5] [Jan 28 19:59:11] [opsiclientd] Searching pids of process name winlogon.exe (session id: 1) (Windows.pyo|565)
[5] [Jan 28 19:59:11] [opsiclientd] Found process winlogon.exe with matching name (pid 464, session 1) (Windows.pyo|587)
[4] [Jan 28 19:59:11] [opsiclientd] Executing: 'C:\Program Files\\opsi.org\\preloginloader\\opsiclientd_rpc.exe "win-itsc9cmgkf1.shields.local" "*** confidential ***" "4441" "setCurrentActiveDesktopName("1", System.getActiveDesktopName())"' in session '1' on desktop 'winsta0\default' (Windows.pyo|716)
[5] [Jan 28 19:59:11] [opsiclientd] Process startet, pid: 1596 (Windows.pyo|718)
[5] [Jan 28 19:59:11] [opsiclientd] Waiting for process ending: 1596 (timeout: 60 seconds) (Windows.pyo|721)
[4] [Jan 28 19:59:11] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:11] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:11] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:11] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[5] [Jan 28 19:59:14] [control server] Worker <opsiclientd.ControlServerJsonRpcWorker instance at 0x0193E3C8> started processing (opsiclientd.pyo|1398)
[4] [Jan 28 19:59:14] [control server] Authorization request from win-itsc9cmgkf1.shields.local@127.0.0.1 (opsiclientd.pyo|1347)
[4] [Jan 28 19:59:14] [json rpc] ==>>> Executing: getPossibleMethods_listOfHashes() (opsiclientd.pyo|928)
[5] [Jan 28 19:59:14] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:14] [control server] Worker <opsiclientd.ControlServerJsonRpcWorker instance at 0x0193E238> started processing (opsiclientd.pyo|1398)
[4] [Jan 28 19:59:14] [control server] Authorization request from win-itsc9cmgkf1.shields.local@127.0.0.1 (opsiclientd.pyo|1347)
[4] [Jan 28 19:59:14] [json rpc] ==>>> Executing: setCurrentActiveDesktopName(1, 'Winlogon') (opsiclientd.pyo|928)
[4] [Jan 28 19:59:14] [opsiclientd] rpc setCurrentActiveDesktopName: current active desktop name for session 1 set to 'Winlogon' (opsiclientd.pyo|3244)
[5] [Jan 28 19:59:14] [json rpc] Got result (opsiclientd.pyo|956)
[4] [Jan 28 19:59:14] [opsiclientd] Process 1596 ended with exit code 0 (Windows.pyo|731)
[5] [Jan 28 19:59:14] [event processing gui_startup] Searching pids of process name winlogon.exe (session id: 1) (Windows.pyo|565)
[5] [Jan 28 19:59:14] [event processing gui_startup] Found process winlogon.exe with matching name (pid 464, session 1) (Windows.pyo|587)
[4] [Jan 28 19:59:14] [event processing gui_startup] Executing: 'C:\Program Files\\opsi.org\\preloginloader\\notifier.exe -p 44001 -s notifier\\action.ini' in session '1' on desktop 'winsta0\Winlogon' (Windows.pyo|716)
[5] [Jan 28 19:59:14] [event processing gui_startup] Process startet, pid: 348 (Windows.pyo|718)
[5] [Jan 28 19:59:14] [event processing gui_startup] Session id set to 1 (opsiclientd.pyo|2238)
[5] [Jan 28 19:59:15] [control server] client connection made (Util.pyo|459)
[5] [Jan 28 19:59:15] [control server] sending notification 'subjectsChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:16] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:16] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:16] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:16] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[4] [Jan 28 19:59:17] [event processing gui_startup] Getting config from service (opsiclientd.pyo|2400)
[5] [Jan 28 19:59:17] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:17] [event processing gui_startup] Starting ServiceConnectionThread, timeout is 10 seconds (opsiclientd.pyo|2309)
[4] [Jan 28 19:59:17] [service connection] Connecting to config server 'https://172.16.201.101:4447/rpc' #1 (opsiclientd.pyo|2146)
[5] [Jan 28 19:59:17] [service connection] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:17] [service connection] Opening https connection to 172.16.201.101:4447 (JSONRPC.pyo|172)
[5] [Jan 28 19:59:18] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:18] [service connection] Successfully connected to '172.16.201.101:4447' (JSONRPC.pyo|186)
[5] [Jan 28 19:59:19] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:20] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:21] [event generator wait_for_gui] Event generator '<GUIStartupEventGenerator(Thread-9, started daemon)>' exiting (opsiclientd.pyo|641)
[5] [Jan 28 19:59:21] [event generator gui_startup] Event generator '<GUIStartupEventGenerator(Thread-8, started daemon)>' exiting (opsiclientd.pyo|641)
[5] [Jan 28 19:59:21] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:21] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:21] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:21] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:21] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[5] [Jan 28 19:59:22] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:23] [service connection] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:23] [service connection] Connected to config server 'https://172.16.201.101:4447/rpc' (opsiclientd.pyo|2158)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'subjectsChanged' to clients (Util.pyo|550)
[5] [Jan 28 19:59:23] [opsiclientd] Setting config value config_service.server_id (opsiclientd.pyo|3368)
[5] [Jan 28 19:59:23] [event processing gui_startup] Updated config_service.host_id to 'opsiserver.shields.local' (opsiclientd.pyo|2344)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:23] [opsiclientd] Updating config file: 'C:\Program Files\opsi.org\preloginloader\opsiclientd\opsiclientd.conf' (opsiclientd.pyo|3461)
[4] [Jan 28 19:59:23] [opsiclientd] No need to write config file 'C:\Program Files\opsi.org\preloginloader\opsiclientd\opsiclientd.conf', config file is up to date (opsiclientd.pyo|3490)
[5] [Jan 28 19:59:23] [event processing gui_startup] Verifying modules file signature (opsiclientd.pyo|2364)
[2] [Jan 28 19:59:23] [event processing gui_startup] C:\Program Files\opsi.org\preloginloader\lib\library.zip\opsiclientd.py DeprecationWarning: getPublicKeyObject is deprecated since Twisted Conch 0.9. Use Key.fromString().
(warnings.pyo|133)
[5] [Jan 28 19:59:23] [event processing gui_startup] Modules file signature verified (opsiclientd.pyo|2379)
[5] [Jan 28 19:59:23] [opsiclientd] Setting config value depot_server.drive (opsiclientd.pyo|3368)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'nextBootServiceURL' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'utilsUrl' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'configUrl' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'utilsDrive' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'opsiServer' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'nextBootServerType' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'depotUrl' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [opsiclientd] Setting config value depot_server.depot_id (opsiclientd.pyo|3368)
[5] [Jan 28 19:59:23] [opsiclientd] Setting config value depot_server.url (opsiclientd.pyo|3368)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'configDrive' (opsiclientd.pyo|2414)
[5] [Jan 28 19:59:23] [event processing gui_startup] Unhandled network config key 'winDomain' (opsiclientd.pyo|2414)
[4] [Jan 28 19:59:23] [event processing gui_startup] Got network config from service (opsiclientd.pyo|2416)
[4] [Jan 28 19:59:23] [event processing gui_startup] Got config from service (opsiclientd.pyo|2429)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:23] [opsiclientd] Updating config file: 'C:\Program Files\opsi.org\preloginloader\opsiclientd\opsiclientd.conf' (opsiclientd.pyo|3461)
[4] [Jan 28 19:59:23] [opsiclientd] No need to write config file 'C:\Program Files\opsi.org\preloginloader\opsiclientd\opsiclientd.conf', config file is up to date (opsiclientd.pyo|3490)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:23] [event processing gui_startup] Got product action requests from configservice (opsiclientd.pyo|2719)
[4] [Jan 28 19:59:23] [event processing gui_startup] No product action requests set (opsiclientd.pyo|2727)
[5] [Jan 28 19:59:23] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:26] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:26] [opsiclientd] rpc getBlockLogin: blockLogin is 'True' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:26] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:26] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[5] [Jan 28 19:59:26] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[3] [Jan 28 19:59:26] [opsiclientd] Failed to get rebootRequested from registry: [Error 2] The system cannot find the file specified (opsiclientd.pyo|3906)
[5] [Jan 28 19:59:26] [opsiclientd] rebootRequested: 0 (opsiclientd.pyo|3907)
[5] [Jan 28 19:59:26] [opsiclientd] shutdownRequested: 0 (opsiclientd.pyo|3926)
[4] [Jan 28 19:59:26] [event processing gui_startup] Writing log to service (opsiclientd.pyo|2444)
[5] [Jan 28 19:59:26] [event processing gui_startup] sending notification 'messageChanged' to clients (Util.pyo|550)
[4] [Jan 28 19:59:27] [event processing gui_startup] Stopping notifier application (pid 348) (opsiclientd.pyo|2507)
[5] [Jan 28 19:59:30] [control server] client connection lost (Util.pyo|464)
[4] [Jan 28 19:59:30] [opsiclientd] Block login now set to 'False' (opsiclientd.pyo|3335)
[5] [Jan 28 19:59:30] [event processing gui_startup] Stopping notification server (opsiclientd.pyo|3065)
[4] [Jan 28 19:59:30] [event processing gui_startup] ============= EventProcessingThread for event '<opsiclientd.GUIStartupEvent object at 0x01921810>' ended ============= (opsiclientd.pyo|3074)
[4] [Jan 28 19:59:30] [opsiclientd] Done processing event '<opsiclientd.GUIStartupEvent object at 0x01921810>' (opsiclientd.pyo|3835)
[4] [Jan 28 19:59:31] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:31] [opsiclientd] rpc getBlockLogin: blockLogin is 'False' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:31] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:31] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)
[4] [Jan 28 19:59:31] [json rpc] ==>>> Executing: getBlockLogin() (opsiclientd.pyo|928)
[4] [Jan 28 19:59:31] [opsiclientd] rpc getBlockLogin: blockLogin is 'False' (opsiclientd.pyo|3139)
[5] [Jan 28 19:59:31] [json rpc] Got result (opsiclientd.pyo|956)
[5] [Jan 28 19:59:31] [control pipe] Creating pipe \\.\pipe\opsiclientd (opsiclientd.pyo|1178)

(there is no instlog.txt)

and on the server:

[4] [Jan 29 03:59:24] Client '172.16.201.129' did not send cookie (opsiconfd|261)
[4] [Jan 29 03:59:24] New session created (opsiconfd|950)
[4] [Jan 29 03:59:24] Authorization request from win-itsc9cmgkf1.shields.local@172.16.201.129 (opsiconfd|354)
[3] [Jan 29 03:59:28] Failed to resolve hostname 'win-itsc9cmgkf1.shields.local': (-2, 'Name or service not known') (opsiconfd|371)
[4] [Jan 29 03:59:28] Host login attempt with username 'win-itsc9cmgkf1.shields.local' from ip '172.16.201.129', but name resolves to '[]', ip verification is disabled (access granted) (opsiconfd|384)
[4] [Jan 29 03:59:28] BackendManager created. (opsiconfd|391)
[4] [Jan 29 03:59:32] Session 'ljpMuZl8YX4k5zh1hTfStr8kVKOMs5Q5' deleted (opsiconfd|984)

that's it..

Thanks again!

[j.schneider]

Looks good, no problems.
Did you set a product action request in configed?
Please post the output of:

Code: Alles auswählen

opsi-admin -d method getProductActionRequests_listOfHashes opsiwin-itsc9cmgkf1.shields.local

[cshields]

I've started from scratch, fresh opsi vm, test product packages, and win7 client. All is working with this so I'm guessing that something I did in the previous setups goofed them up..

and it works rather well so far.

Thanks!
-Corey

[mansukhpatel]

I've started from scratch, fresh opsi vm, test product packages, and win7 client. All is working with this so I'm guessing that something I did in the previous setups goofed them up..

and it works rather well so far.

Thanks!
-Corey

Hello mate,

Can I ask where you found the instructions for setting up OPSI from scratch?