Seite 1 von 1

Produkt Caching - maximum freeable space

Verfasst: 02 Apr 2014, 12:39
von Vinc
Guten Tag,

ich versuche zur Zeit den Produkt Cache Modus zu aktivieren und habe folgende Änderungen vorgenommen:

opsiclientd.cache_service product_cach_max_size = 52428800
opsiclientd.event_default.cache_products = true
opsiclientd.event_default.cache_max_bandwidth = 1000
opsiclientd.event_default.cache_dynamic_bandwidth = true

Beim startup sieht auch alles gut aus und der Ordner wird unter C:\opsi.org\cache\depot angelegt allerdings bekomm ich jedesmal eine Fehlermeldung. Diese Fehlermeldung sieht zudem jedesmal anders aus (needed space 265mb, maximum freeable space 250mb etc.) Das Paket ist 117MB groß und beim normalen installieren gibt es kein Problem.

Errors occured while caching products opt-bla-bla, opsi-winst: Failed to free enough disk space for product cache: Needed space: 65.748 MB, maximum freeable space: 0.002 MB

Wo liegt hier der Fehler? Mehr einstellungen habe ich im vergleich zum Standard nicht gemacht.

Mit freundlichem Gruß
Vincent Thomas

Re: Produkt Caching - maximum freeable space

Verfasst: 02 Apr 2014, 13:01
von ueluekmen
Hallo Vincent,

klingt komisch. Was immer ein bisschen irritiert an der Stelle, ist das der winst auch mit gecached wird, dies geschieht automatisch. Aber das Winst-Verzeichnis im Depot ist keine 65MB groß.

So kann man dazu nichts sagen.

Welche Version vom opsi-client-agent hast du? Ist das die aktuelle? Wenn ja, müssten wir mal in eine Logdatei schauen. Ohne wird es schwer, dir irgendwas zu sagen.

Re: Produkt Caching - maximum freeable space

Verfasst: 02 Apr 2014, 14:44
von Vinc
also bei mir im opsi stehen folgende versionen: opsi-client-agent 4.0.3.1-4 und opsi-winst 4.11.3.6-1
[Apr 02 12:08:22] [ event processing gui_startup ] Choosing depot with networkAddress 192.168.248.0/255.255.252.0 for ip 192.168.248.23 (<string>|45)
[5] [Apr 02 12:08:22] [ event processing gui_startup ] Selected depot is: <OpsiConfigserver id 'opsi.stute.loc'> (Config.pyo|505)
[6] [Apr 02 12:08:22] [ event processing gui_startup ] Setting config value depot_server.depot_id (Config.pyo|198)
[6] [Apr 02 12:08:22] [ event processing gui_startup ] Setting config value depot_server.url (Config.pyo|198)
[5] [Apr 02 12:08:22] [ event processing gui_startup ] Getting TrustedInstaller service configuration (EventProcessing.pyo|715)
[6] [Apr 02 12:08:22] [ opsiclientd ] Searching pids of process name winlogon.exe (session id: 1) (Windows.pyo|1335)
[6] [Apr 02 12:08:22] [ opsiclientd ] Found process winlogon.exe with matching name (pid 872, session 1) (Windows.pyo|1357)
[5] [Apr 02 12:08:22] [ opsiclientd ] Executing: 'C:\Program Files (x86)\opsi.org\opsi-client-agent\\opsiclientd_rpc.exe "mycomputer" "*** confidential ***" "4441" "setCurrentActiveDesktopName("1", System.getActiveDesktopName())"' in session '1' on desktop 'winsta0\winlogon' (Windows.pyo|1514)
[6] [Apr 02 12:08:22] [ opsiclientd ] Process startet, pid: 3832 (Windows.pyo|1517)
[6] [Apr 02 12:08:22] [ opsiclientd ] Waiting for process ending: 3832 (timeout: 60 seconds) (Windows.pyo|1520)
[6] [Apr 02 12:08:24] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x024A0120> started processing (Worker.pyo|244)
[5] [Apr 02 12:08:24] [ control server ] New session created (Session.pyo|179)
[5] [Apr 02 12:08:24] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:24] [ opsiclientd ] -----> Executing: backend_getInterface() (JsonRpc.pyo|123)
[6] [Apr 02 12:08:24] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[6] [Apr 02 12:08:24] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x0257E0F8> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:24] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:24] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:24] [ opsiclientd ] -----> Executing: setCurrentActiveDesktopName(1, u'Winlogon') (JsonRpc.pyo|123)
[5] [Apr 02 12:08:24] [ opsiclientd ] rpc setCurrentActiveDesktopName: current active desktop name for session 1 set to 'Winlogon' (ControlServer.pyo|536)
[6] [Apr 02 12:08:24] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[6] [Apr 02 12:08:24] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x024A2378> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:24] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:24] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:24] [ opsiclientd ] -----> Executing: backend_exit() (JsonRpc.pyo|123)
[6] [Apr 02 12:08:24] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[5] [Apr 02 12:08:24] [ opsiclientd ] Process 3832 ended with exit code 0 (Windows.pyo|1530)
[5] [Apr 02 12:08:24] [ event processing gui_startup ] Updating action processor (EventProcessing.pyo|401)
[5] [Apr 02 12:08:24] [ event processing gui_startup ] Mounting depot share smb://opsi.stute.loc/opt_pcbin/install (EventProcessing.pyo|369)
[6] [Apr 02 12:08:24] [ event processing gui_startup ] Added depot 'opsi.stute.loc' to trusted domains (EventProcessing.pyo|378)
[5] [Apr 02 12:08:24] [ event processing gui_startup ] Mounting '\\opsi.stute.loc\opt_pcbin' to 'p:' (Windows.pyo|670)
[6] [Apr 02 12:08:24] [ product cache service ] Transfered 0.29 kByte in 0.00 minutes, average speed was 18.19 kByte/s (Repository.pyo|370)
[6] [Apr 02 12:08:24] [ product cache service ] Product 'opt-bla-bla' contains 5 files with a total size of 109.452 MB (CacheService.pyo|885)
[6] [Apr 02 12:08:24] [ product cache service ] Product cache dir sizelimit of 50.000 MB exceeded. Current size: 6.295 MB, space needed for product 'opt-bla-bla': 109.452 MB (CacheService.pyo|893)
[6] [Apr 02 12:08:24] [ product cache service ] Traceback: (Logger.pyo|742)
[6] [Apr 02 12:08:24] [ product cache service ] line 756 in '_cacheProducts' in file 'ocdlibnonfree\CacheService.pyo' (Logger.pyo|742)
[6] [Apr 02 12:08:24] [ product cache service ] line 950 in '_cacheProduct' in file 'ocdlibnonfree\CacheService.pyo' (Logger.pyo|742)
[6] [Apr 02 12:08:24] [ product cache service ] ==>>> Failed to free enough disk space for product cache: Needed space: 65.748 MB, maximum freeable space: 0.002 MB (CacheService.pyo|758)
[5] [Apr 02 12:08:25] [ event processing gui_startup ] Updating action processor from depot dir 'p:\install\opsi-winst\\files\\opsi-winst' (EventProcessing.pyo|448)
[5] [Apr 02 12:08:25] [ event processing gui_startup ] Local action processor exists and seems to be up to date (EventProcessing.pyo|459)
[5] [Apr 02 12:08:25] [ event processing gui_startup ] Unmounting depot share (EventProcessing.pyo|394)
[5] [Apr 02 12:08:25] [ event processing gui_startup ] Starting action processor in session '1' on desktop 'winlogon' (EventProcessing.pyo|811)
[6] [Apr 02 12:08:25] [ event processing gui_startup ] Running command C:\Program Files (x86)\opsi.org\opsi-client-agent\action_processor_starter.exe "mycomputer" "*** confidential ***" "4441" "c:\\tmp\\opsiclientd.log" "6" "smb://opsi.stute.loc/opt_pcbin/install" "p:" "pcpatch" "*** confidential ***" "1" "winlogon" "\"C:\Program Files (x86)\opsi.org\opsi-client-agent\\opsi-winst\\winst32.exe\" /opsiservice \"https://opsi.stute.loc:4447/rpc\" /clientid stute-pc-0441.stute.loc /username mycomputer /password *** confidential *** " 10800 "" "" false in session '1' on desktop 'winlogon' (EventProcessing.pyo|301)
[6] [Apr 02 12:08:25] [ event processing gui_startup ] Searching pids of process name winlogon.exe (session id: 1) (Windows.pyo|1335)
[6] [Apr 02 12:08:25] [ event processing gui_startup ] Found process winlogon.exe with matching name (pid 872, session 1) (Windows.pyo|1357)
[5] [Apr 02 12:08:25] [ event processing gui_startup ] Executing: 'C:\Program Files (x86)\opsi.org\opsi-client-agent\action_processor_starter.exe "mycomputer" "*** confidential ***" "4441" "c:\\tmp\\opsiclientd.log" "6" "smb://opsi.stute.loc/opt_pcbin/install" "p:" "pcpatch" "*** confidential ***" "1" "winlogon" "\"C:\Program Files (x86)\opsi.org\opsi-client-agent\\opsi-winst\\winst32.exe\" /opsiservice \"https://opsi.stute.loc:4447/rpc\" /clientid mycomputer /username mycomputer /password *** confidential *** " 10800 "" "" false' in session '1' on desktop 'winsta0\winlogon' (Windows.pyo|1514)
[5] [Apr 02 12:08:25] [ product cache service ] Caching product 'opsi-winst' (max bandwidth: 0, dynamic bandwidth: False) (CacheService.pyo|852)
[6] [Apr 02 12:08:25] [ event processing gui_startup ] Process startet, pid: 3916 (Windows.pyo|1517)
[6] [Apr 02 12:08:25] [ event processing gui_startup ] Waiting for process ending: 3916 (timeout: 0 seconds) (Windows.pyo|1520)
[5] [Apr 02 12:08:25] [ product cache service ] Selecting depot for products [u'opsi-winst'] (Config.pyo|380)
[5] [Apr 02 12:08:25] [ product cache service ] MasterOnly --> 'False' (Config.pyo|381)
[6] [Apr 02 12:08:25] [ product cache service ] Dynamic depot selection enabled (Config.pyo|433)
[6] [Apr 02 12:08:25] [ product cache service ] Master depot for products [u'opsi-winst'] is opsi.stute.loc (Config.pyo|461)
[6] [Apr 02 12:08:25] [ product cache service ] Got alternative depots for products: [u'opsi-winst'] (Config.pyo|465)

[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE90> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE40> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE90> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE40> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE90> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE40> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE90> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE40> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE90> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Found network interface: <OPSI.System.Windows.IP_ADAPTER_INFO object at 0x0254DE40> (Config.pyo|476)
[6] [Apr 02 12:08:26] [ product cache service ] Passing client configuration to depot selection algorithm: {'netmask': u'255.255.252.0', 'opsiHostKey': u'*** confidential ***', 'ipAddress': u'192.168.248.23', 'defaultGateway': u'192.168.251.254', 'clientId': u'mycomputer'} (Config.pyo|492)
[6] [Apr 02 12:08:26] [ product cache service ] Choosing depot from list of depots: (<string>|4)
[6] [Apr 02 12:08:26] [ product cache service ] Master depot: <OpsiConfigserver id 'opsi.stute.loc'> (<string>|5)

[5] [Apr 02 12:08:26] [ product cache service ] Choosing depot with networkAddress 192.168.248.0/255.255.252.0 for ip 192.168.248.23 (<string>|45)
[5] [Apr 02 12:08:26] [ product cache service ] Selected depot is: <OpsiConfigserver id 'opsi.stute.loc'> (Config.pyo|505)
[6] [Apr 02 12:08:26] [ product cache service ] Setting config value depot_server.depot_id (Config.pyo|198)
[6] [Apr 02 12:08:26] [ product cache service ] Setting config value depot_server.url (Config.pyo|198)
[6] [Apr 02 12:08:26] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x024968F0> started processing (Worker.pyo|244)
[5] [Apr 02 12:08:26] [ control server ] New session created (Session.pyo|179)
[6] [Apr 02 12:08:26] [ product cache service ] Transfered 1.89 kByte in 0.00 minutes, average speed was 118.35 kByte/s (Repository.pyo|370)
[5] [Apr 02 12:08:26] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:26] [ opsiclientd ] -----> Executing: backend_getInterface() (JsonRpc.pyo|123)
[6] [Apr 02 12:08:26] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[6] [Apr 02 12:08:26] [ product cache service ] Product 'opsi-winst' contains 27 files with a total size of 6.291 MB (CacheService.pyo|885)
[6] [Apr 02 12:08:26] [ action_processor_starter.exe ] <OPSI.Backend.JSONRPC.JSONRPCBackend instance at 0x0153F8F0>: Connected to service (JSONRPC.pyo|427)
[6] [Apr 02 12:08:26] [ action_processor_starter.exe ] Impersonating network account 'pcpatch' (action_processor_starter.py|98)
[5] [Apr 02 12:08:26] [ action_processor_starter.exe ] Mounting depot share smb://opsi.stute.loc/opt_pcbin/install (action_processor_starter.py|103)
[6] [Apr 02 12:08:26] [ product cache service ] Disk space usage for path 'C:\opsi.org\cache\depot': {'available': 46435848192L, 'usage': 0.70927665261552686, 'used': 113289363456L, 'capacity': 159725211648L} (Windows.pyo|621)
[5] [Apr 02 12:08:26] [ product cache service ] Syncing product opsi-winst of depot <CIFSRepository smb://opsi.stute.loc/opt_pcbin/install> with local directory C:\opsi.org\cache\depot (Repository.pyo|1318)
[6] [Apr 02 12:08:26] [ product cache service ] Downloading package content file (Repository.pyo|1324)
[6] [Apr 02 12:08:26] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x02CB3DC8> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:26] [ product cache service ] Transfered 1.89 kByte in 0.00 minutes, average speed was 18935546.88 kByte/s (Repository.pyo|370)
[6] [Apr 02 12:08:26] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:26] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:26] [ opsiclientd ] -----> Executing: setStatusMessage(u'1', u'Verbinde mit Depot-Freigabe smb://opsi.stute.loc/opt_pcbin/install') (JsonRpc.pyo|123)
[5] [Apr 02 12:08:26] [ opsiclientd ] DEBUG: [<EventProcessingThread(Thread-16, started daemon 3000)>] (Opsiclientd.pyo|434)
[5] [Apr 02 12:08:26] [ opsiclientd ] DEBUG: 1 (Opsiclientd.pyo|436)
[5] [Apr 02 12:08:26] [ opsiclientd ] rpc setStatusMessage: Setting status message to 'Verbinde mit Depot-Freigabe smb://opsi.stute.loc/opt_pcbin/install' (ControlServer.pyo|515)
[6] [Apr 02 12:08:26] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[5] [Apr 02 12:08:26] [ action_processor_starter.exe ] Mounting '\\opsi.stute.loc\opt_pcbin' to 'p:' (Windows.pyo|670)
[5] [Apr 02 12:08:26] [ action_processor_starter.exe ] Starting action processor (action_processor_starter.py|112)
[6] [Apr 02 12:08:26] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x02CAEEB8> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:26] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:26] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:26] [ opsiclientd ] -----> Executing: setStatusMessage(u'1', u'Action processor l\xe4uft') (JsonRpc.pyo|123)
[5] [Apr 02 12:08:26] [ opsiclientd ] DEBUG: [<EventProcessingThread(Thread-16, started daemon 3000)>] (Opsiclientd.pyo|434)
[5] [Apr 02 12:08:26] [ opsiclientd ] DEBUG: 1 (Opsiclientd.pyo|436)
[5] [Apr 02 12:08:26] [ opsiclientd ] rpc setStatusMessage: Setting status message to 'Action processor läuft' (ControlServer.pyo|515)
[6] [Apr 02 12:08:26] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[5] [Apr 02 12:08:26] [ action_processor_starter.exe ] Running command '"C:\Program Files (x86)\opsi.org\opsi-client-agent\\opsi-winst\\winst32.exe" /opsiservice "https://opsi.stute.loc:4447/rpc" /clientid stute-pc-0441.stute.loc /username stute-pc-0441.stute.loc /password *** confidential *** ' as user 'pcpatch' on desktop 'winlogon' (Windows.pyo|1767)
[6] [Apr 02 12:08:26] [ action_processor_starter.exe ] Process startet, pid: 3996 (Windows.pyo|1771)
[6] [Apr 02 12:08:26] [ action_processor_starter.exe ] Waiting for process ending: 3996 (timeout: 10800 seconds) (Windows.pyo|1774)
[5] [Apr 02 12:08:28] [ product cache service ] Product 'opsi-winst' cached (CacheService.pyo|928)
[3] [Apr 02 12:08:29] [ product cache service ] Errors occured while caching products opt-fleetboard-cockpit, opsi-winst: Failed to free enough disk space for product cache: Needed space: 65.748 MB, maximum freeable space: 0.002 MB (CacheService.pyo|765)
[5] [Apr 02 12:08:35] [ action_processor_starter.exe ] Process 3996 ended with exit code 0 (Windows.pyo|1784)
[5] [Apr 02 12:08:35] [ action_processor_starter.exe ] Action processor ended (action_processor_starter.py|117)
[6] [Apr 02 12:08:35] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x02CA9E40> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:35] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:35] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:35] [ opsiclientd ] -----> Executing: setStatusMessage(u'1', u'Action processor beendet') (JsonRpc.pyo|123)
[5] [Apr 02 12:08:35] [ opsiclientd ] DEBUG: [<EventProcessingThread(Thread-16, started daemon 3000)>] (Opsiclientd.pyo|434)
[5] [Apr 02 12:08:35] [ opsiclientd ] DEBUG: 1 (Opsiclientd.pyo|436)
[5] [Apr 02 12:08:35] [ opsiclientd ] rpc setStatusMessage: Setting status message to 'Action processor beendet' (ControlServer.pyo|515)
[6] [Apr 02 12:08:35] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[5] [Apr 02 12:08:35] [ action_processor_starter.exe ] Unmounting depot share (action_processor_starter.py|132)
[6] [Apr 02 12:08:35] [ control server ] Worker <ocdlib.ControlServer.WorkerOpsiclientdJsonRpc instance at 0x02CB7990> started processing (Worker.pyo|244)
[6] [Apr 02 12:08:35] [ control server ] Reusing session for client '127.0.0.1', application 'opsi jsonrpc module version 4.0.2' (Worker.pyo|391)
[5] [Apr 02 12:08:35] [ control server ] Authorization request from mycomputer@127.0.0.1 (application: opsi jsonrpc module version 4.0.2) (ControlServer.pyo|144)
[5] [Apr 02 12:08:35] [ opsiclientd ] -----> Executing: backend_exit() (JsonRpc.pyo|123)
[6] [Apr 02 12:08:35] [ opsiclientd ] Got result (JsonRpc.pyo|131)
[5] [Apr 02 12:08:35] [ event processing gui_startup ] Process 3916 ended with exit code 0 (Windows.pyo|1530)

Re: Produkt Caching - maximum freeable space

Verfasst: 03 Apr 2014, 09:28
von ueluekmen
Ok, du solltest deinen Server Updaten, wenn nicht schon geschehen und du solltest auch den neuesten opsi-cient-agent ausprobieren. Wir haben vor einige Zeit an diesen Stellen einiges gefixed.