Opsi-Package-Manager timed Out

[KessiJones]

Hallo zusammen,

ich bin jetzt recht lange auf der Suche nach einer Lösung, aber so recht will hier keine passen !
Ich habe OPSI Version 4.0.1.7 vom 30.03.2011 auf einer VM-Ware installiert.
Ich habe alle Schritte innerhalb des Getting-Started-Handbuchs durch.
Soweit funktioniert auch alles.
Ich habe einen PC mit dem OPSI-CLIENT-AGENT isntalliert und kann z.b. SWAUDIT per "ON_DEMAND" dort installieren !

Um weiter zu arbeiten habe ich über opsi-newprod um VZ /home/opsiproducts ein Verzeichnis für FlashPlayer angelegt.
Das Erstellen des OPSI-Paketes funktioniert auch noch !

Wenn ich aber nun versuche das erstellte Paket mit "opsi-package-manager -i flashplayer.opsi" in den Depotserver zu installieren, kommt dire Meldung:

"Es sind Fehler aufgetreten:
Fehler beim Bearbeiten von Install auf Package(s) /home/opsiproducts/flashplayer.opsi in depot 'opsi-srv.local':
Opsi timeout error: Timed out after 30 seconds (timed out) "

Für Tipps, wie ich die Pakete nun installieren kann, wäre ich sehr dankbar, denn ich glaube, vieles, was in anderen Threads drinn steht, habe ich schon ausprobiert.
z.B. opsi-setup --set-rights etc.

opsi-package-manager -l oder ähnliches funktioniert ebenfalls problemlos.

Vielen Dank für eure Hilfe.

Ralf

[d.oertel]

Hi,

bitte den exacten Mitschnitt
sowie die entprechenden Abschnitte der Datei

/var/log/opsi/package.log

Läuft der opsiconfd ?

gruss
d.oertel

[KessiJones]

Hallo,

der opsiconfd läuft. Ja.
Ich kann mich auch von einem loakeln PC per Browser verbinden !

Im Aktuellen Verzeichnis steht nur eine alte package.log. wenn ich den package-manager aufrufe, wird dieses logfile nicht geändert !

Gruß
Ralf

[KessiJones]

Das ist ja kalr, dass das LOG nicht angepaßt wurde !!
Hab ja auch den Parameter vergessen, für das LOG-File !

Welchen Teil der Infos brauchst du denn ? Ist sehr umfangreich

ich hänge mal n Teil an, vielleicht reicht das ja schon !!!!



[7] [Mär 22 13:47:50] File '/var/lib/opsi/config/depots/opsi-srv.elo-nie.local.ini' locked after 0 millis (__init__.py|211)
[7] [Mär 22 13:47:50] Finished reading file after 0.004 seconds (__init__.py|564)
[8] [Mär 22 13:47:50] Unescaping string: '' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '00:0c:29:73:54:85' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '129.20.112.26' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '129.20.112.0/255.255.255.0' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: 'smb://opsi-srv/opt_pcbin/install' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: 'webdavs://opsi-srv.elo-nie.local:4447/depot' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: 'file:///opt/pcbin/install' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: 'webdavs://opsi-srv.elo-nie.local:4447/repository' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: 'file:///var/lib/opsi/repository' (File.py|290)
[8] [Mär 22 13:47:50] Unescaping string: '0' (File.py|290)
[8] [Mär 22 13:47:50] Got object hash from ini file: {'networkAddress': u'129.20.112.0/255.255.255.0', 'description': u'', 'inventoryNumber': u'', 'notes': u'', 'depotLocalUrl': u'file:///opt/pcbin/install', 'isMasterDepot': True, 'repositoryRemoteUrl': u'webdavs://opsi-srv.elo-nie.local:4447/repository', 'hardwareAddress': u'00:0c:29:73:54:85', 'maxBandwidth': u'0', 'repositoryLocalUrl': u'file:///var/lib/opsi/repository', 'ipAddress': u'129.20.112.26', 'id': u'opsi-srv.elo-nie.local', 'depotWebdavUrl': u'webdavs://opsi-srv.elo-nie.local:4447/depot', 'depotRemoteUrl': u'smb://opsi-srv/opt_pcbin/install'} (File.py|845)
[7] [Mär 22 13:47:50] Getting config file for 'OpsiConfigserver', '{'id': u'opsi-srv.elo-nie.local'}', 'key' (File.py|295)
[8] [Mär 22 13:47:50] Returning config file '/etc/opsi/pckeys' (File.py|377)
[7] [Mär 22 13:47:50] Trying to lock file '/etc/opsi/pckeys' (0/2000) (__init__.py|189)
[7] [Mär 22 13:47:50] File '/etc/opsi/pckeys' locked after 0 millis (__init__.py|211)
[7] [Mär 22 13:47:50] Trying to lock file '/etc/opsi/pckeys' (0/2000) (__init__.py|189)
[7] [Mär 22 13:47:50] File '/etc/opsi/pckeys' locked after 0 millis (__init__.py|211)
[7] [Mär 22 13:47:50] Testing match of filter 'opsi-srv.elo-nie.local' of attribute 'id' with value 'opsi-srv.elo-nie.local' (Backend.py|192)
[7] [Mär 22 13:47:50] Value 'opsi-srv.elo-nie.local' matched filter 'opsi-srv.elo-nie.local', attribute 'id' (Backend.py|240)
[7] [Mär 22 13:47:50] Testing match of filter 'OpsiDepotserver' of attribute 'type' with value 'OpsiConfigserver' (Backend.py|192)
[7] [Mär 22 13:47:50] Value 'OpsiConfigserver' matched filter 'OpsiDepotserver', attribute 'type' (Backend.py|240)
[8] [Mär 22 13:47:50] Adapting objectHash with '{'networkAddress': u'129.20.112.0/255.255.255.0', 'description': u'', 'inventoryNumber': u'', 'type': 'OpsiConfigserver', 'notes': u'', 'depotLocalUrl': u'file:///opt/pcbin/install', 'isMasterDepot': True, 'repositoryRemoteUrl': u'webdavs://opsi-srv.elo-nie.local:4447/repository', 'hardwareAddress': u'00:0c:29:73:54:85', 'maxBandwidth': u'0', 'repositoryLocalUrl': u'file:///var/lib/opsi/repository', 'opsiHostKey': u'0e7788e1a7f29320218e583956c6382e', 'ipAddress': u'129.20.112.26', 'id': u'opsi-srv.elo-nie.local', 'depotWebdavUrl': u'webdavs://opsi-srv.elo-nie.local:4447/depot', 'depotRemoteUrl': u'smb://opsi-srv/opt_pcbin/install'}', '{'id': u'opsi-srv.elo-nie.local'}', '[]' (File.py|712)
[8] [Mär 22 13:47:50] Returning object: opsi-srv.elo-nie.local (File.py|880)
[7] [Mär 22 13:47:50] Object type 'OpsiClient' does not match filter {'type': 'OpsiDepotserver', 'id': u'opsi-srv.elo-nie.local'} (File.py|741)
[7] [Mär 22 13:47:50] Executing jsonrpc method 'backend_getInterface' on host opsi-srv.elo-nie.local (JSONRPC.py|104)
[8] [Mär 22 13:47:50] jsonrpc: {"params": [], "id": 1, "method": "backend_getInterface"} (JSONRPC.py|107)
[8] [Mär 22 13:47:50] Request to host 'opsi-srv.elo-nie.local', baseUrl: /rpc, query '{"params": [], "id": 1, "method": "backend_getInterface"}' (JSONRPC.py|602)
[7] [Mär 22 13:47:50] Starting new HTTPS connection (0) to opsi-srv.elo-nie.local:4447 (HTTP.py|493)
[7] [Mär 22 13:47:53] timed out (HTTP.py|75)
[7] [Mär 22 13:47:54] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:47:54] [Errno 114] Operation already in progress (HTTP.py|75)
...
[7] [Mär 22 13:48:09] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:10] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:10] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:11] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:11] [Errno 110] Connection timed out (HTTP.py|75)
[7] [Mär 22 13:48:15] timed out (HTTP.py|75)
[7] [Mär 22 13:48:15] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:16] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:16] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:17] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:17] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:18] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:18] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:19] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:19] [Errno 114] Operation already in progress (HTTP.py|75)
[7] [Mär 22 13:48:20] [Errno 114] Operation already in progress (HTTP.py|75)
[6] [Mär 22 13:48:20] Traceback: (Logger.py|721)
[6] [Mär 22 13:48:20] line 108 in 'process' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 623 in '_request' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 353 in 'urlopen' in file '/usr/lib/pymodules/python2.6/OPSI/Util/HTTP.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 278 in '_get_conn' in file '/usr/lib/pymodules/python2.6/OPSI/Util/HTTP.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 495 in '_new_conn' in file '/usr/lib/pymodules/python2.6/OPSI/Util/HTTP.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 86 in 'non_blocking_connect_https' in file '/usr/lib/pymodules/python2.6/OPSI/Util/HTTP.py' (Logger.py|721)
[6] [Mär 22 13:48:20] line 71 in 'non_blocking_connect_http' in file '/usr/lib/pymodules/python2.6/OPSI/Util/HTTP.py' (Logger.py|721)
[6] [Mär 22 13:48:20] ==>>> Failed to process method 'backend_getInterface': Opsi timeout error: Timed out after 30 seconds (timed out) (JSONRPC.py|112)
[3] [Mär 22 13:48:20] Opsi timeout error: Timed out after 30 seconds (timed out) (opsi-package-manager|1264)
[2] [Mär 22 13:48:20] Traceback: (Logger.py|721)
[2] [Mär 22 13:48:20] line 91 in 'start' in file '/usr/bin/opsi-package-manager' (Logger.py|721)
[2] [Mär 22 13:48:20] line 1242 in 'installPackage' in file '/usr/bin/opsi-package-manager' (Logger.py|721)
[2] [Mär 22 13:48:20] line 697 in 'getDepotConnection' in file '/usr/bin/opsi-package-manager' (Logger.py|721)
[2] [Mär 22 13:48:20] line 317 in '__init__' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[2] [Mär 22 13:48:20] line 382 in 'connect' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[2] [Mär 22 13:48:20] line 591 in '_jsonRPC' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[2] [Mär 22 13:48:20] line 123 in 'execute' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/JSONRPC.py' (Logger.py|721)
[2] [Mär 22 13:48:20] line 133 in 'waitForResult' in file '/usr/lib/pymodules/python2.6/OPSI/Backend/Backend.py' (Logger.py|721)
[2] [Mär 22 13:48:20] ==>>> Opsi timeout error: Timed out after 30 seconds (timed out) (opsi-package-manager|93)
[3] [Mär 22 13:48:20] Task 'Install of package 'flashplayer_11.opsi' on depot 'opsi-srv.elo-nie.local'' failed: Opsi timeout error: Timed out after 30 seconds (timed out) (opsi-package-manager|623)
[6] [Mär 22 13:48:22] Cleaning up (opsi-package-manager|686)
[7] [Mär 22 13:48:22] UserInterface: exitScreen() (opsi-package-manager|494)
[6] [Mär 22 13:48:22] Cleaning up (Product.py|82)
[7] [Mär 22 13:48:22] Calling backend_exit() on backend <OPSI.Backend.Backend.ExtendedConfigDataBackend instance at 0x9f142cc> (Backend.py|426)
[7] [Mär 22 13:48:22] Calling backend_exit() on backend <OPSI.Backend.BackendManager.BackendDispatcher instance at 0x9f1432c> (Backend.py|426)
[3] [Mär 22 13:48:22] Failure while processing Install of package(s) /home/opsiproducts/flashplayer/flashplayer_11.opsi on depot 'opsi-srv.elo-nie.local': (opsi-package-manager|1471)
[3] [Mär 22 13:48:22] Opsi timeout error: Timed out after 30 seconds (timed out) (opsi-package-manager|1474)

[KessiJones]

Um sicher zu gehen, dass es nicht an der Namensauflösung hängt, habe ich aktuell in der Konfiguration des Depots aus dem Namen eine IP-ADresse gemacht (depotWebDavURL)
Das hat aber auch keinen Erfolg gebracht.

Für weitere Hinweise bin ich sehr dankbar !!!!

[d.oertel]

Hi,

bitte noch mal den excaten aufruf

ist das ein standalone opsi server ?

Heist der
opsi-srv.elo-nie.local
und hat die IP
129.20.112.26
?

gruss
d.oertel

[KessiJones]

Hallo,

also der OPSI-Server heißt opsi-srv.elo-nie.local und hat die IP 129.20.112.26 und ist der einzige im Netz. Auch keine anderen Deploy-Server.

Soweit ist das richtig.

Der Aufruf im VZ /home/opsiproducts/flashplayer war " opsi-package-manager -i flashplayer.opsi "

Gruß
Ralf

[d.oertel]

Hi,

die Log datei sagt, das der opsi-package-manager versucht zum web service kontakt aufzunehmen und dabei scheitert.

Gibt es eine lokale Firewall auf der Kiste ?

Was sagt die
/var/log/opsi/opsiconfd/<ip des servers>.log


gruss
d.oertel

[KessiJones]

Hallo,

eine Firewall ist nicht installiert, soweit ich das überblicken kann.

Aber einen Zugang zum WEB hat der Server ebenfalls nicht, da er dafür duch einen Proxy muss.
Muss der eingerichtet sein ?
Firefox kommt ins Internet. Aber ein Ping aus der Konsole sagt: "network unreachable".


Anbei der Inhalt aus dem LOG
[5] [Mar 22 16:05:56] New session created (session.py|74)
[5] [Mar 22 16:05:56] Authorization request from host opsi-srv.elo-nie.local@129.20.112.26 (application: opsi-package-manager version 4.0.0.2) (workers.py|182)
[5] [Mar 22 16:08:40] New session created (session.py|74)
[5] [Mar 22 16:08:40] Authorization request from host opsi-srv.elo-nie.local@129.20.112.26 (application: opsi-package-manager version 4.0.0.2) (workers.py|182)



Gruß
Ralf

[KessiJones]

Keine Idee mehr ???