opsi config editor "Lade Tabelle product property" hängt dauerhaft

[remote_minds]

Der Opsi-Server läuft mit einem SHA1-Zertifikat, wäre es denkbar dass Java 8 hier ein SHA2-Zertifikat sehen möchte?

[r.roeder]

das Log zeigt, dass die Anfrage in einen Timeout läuft; möglicherweise einfach aufgrund der schieren Datenmenge. Eventuell muss ist die Timeout-Zeit für sehr großen Datenmenge nicht ausreichend bemessen. Was sind die ungefähren Datenvolumen (Anzahl Clients, Anzahl Produkte)?

Grüße
Rupert Röder

[isf]

Guten Morgen,

derzeit sind 164 Clients und 84 Produkte in der Datenbank.

Gruß, Jens

[dark alex]

Das iss ja nix...




Was sagt denn dein Serverload? Mal beobachtet ob evtl die CPU-Last exorbitant hoch geht wenn du den configed anmeldest?
Passiert das eigentlich auch an anderen Clients? Oder Lokal am Server (Falls X-Fähig)?

[r.roeder]

Da kann ich Dark Alex nur zustimmen, an der Datenmenge kann es nicht liegen.

Ich würde gern den Log ausführlicher sehen. Dazu bitte den configed mit dem zusätzlichen Parameter --loglevel 4 starten (default ist 3); hilfreich kann auch der entsprechende Zeitabschnitt von /var/log/opsi/opsiconfd/[ip-des-rechners-auf-dem-der-configed-läuft].log sein.

Grüße
Rupert

[dark alex]

du schreibst ja, dass du das MySQL-Backend nutzt.
Hast du schon mal die Tabellen geprüft?

[isf]

Hallo,

Ich würde gern den Log ausführlicher sehen. Dazu bitte den configed mit dem zusätzlichen Parameter --loglevel 4 starten (default ist 3); hilfreich kann auch der entsprechende Zeitabschnitt von /var/log/opsi/opsiconfd/[ip-des-rechners-auf-dem-der-configed-läuft].log sein.

Das Log mit Java 8 auf dem Client: http://pastebin.com/raw/NSnyRMv2

Durch Zufall habe ich im Serverlog folgende (mich doch sehr schockierende) Zeile gefunden:

Code: Alles auswählen

[9] [Aug 29 15:45:22] Client supplied username 'username' and password 'password' (workers.py|117)

Hier wird das Passwort im Klartext geloggt... Ich habe gerade nicht die Zeit das ganze Log noch solchen Sachen abzusuchen, deshalb werde ich das Log fürs erste nicht posten. Es wäre doch sehr schön solche Sachen nicht zu loggen.

Grüße
Andreas

[r.roeder]

Hallo,

wenn die Zeile im Log steht, kann das eigentlich nicht anders sein, als dass auf dem Server ein Root-User den Loglevel auf einen Level noch jenseits von debug gesetzt hat, in der opsiconfd.conf steht explizit das Log level erklärt:

9: passwords

Natürlich sollte man das im Normalfall nie setzen und im Nicht-Normalfall auch nur kurzfristig und mit unmittelbarem Aufräumen hinterher. Ich habe auch nie erlebt, dass es erforderlich ist.

Vielleicht rühren die Probleme des Servers tatsächlich auch von dem extrem gesetzten Loglevel her, der ist nämlich dann mit Schreiben von Logeinträgen beschäftigt.

Also unbedingt SOFORT in der opsiconfd.conf ein niedrigeres Loglevel setzen (5 ist normal völlig ausreichend, 4 ist sogar nur der Default)

Grüße

[isf]

Hallo,

Hallo,
Also unbedingt SOFORT in der opsiconfd.conf ein niedrigeres Loglevel setzen (5 ist normal völlig ausreichend, 4 ist sogar nur der Default)

das war tatsächlich der Fall... Ist jetzt auf 5.

Das Log am Server bei einem Client mit Java 8:

Code: Alles auswählen

[5] [Sep 05 15:02:43] Application 'opsi config editor 4.0.7.1.3' on client 'IP' did not send cookie (workers.py|166)
[5] [Sep 05 15:02:43] New session created (session.py|77)
[5] [Sep 05 15:02:43] Authorization request from USER@IP (application: opsi config editor 4.0.7.1.3) (workers.py|196)
[5] [Sep 05 15:02:43] Modules file signature verified (customer: NAME) (MySQL.py|523)
[5] [Sep 05 15:02:44] -----> Executing: authenticated() (JsonRpc.py|134)
[5] [Sep 05 15:02:44] -----> Executing: getRawData(u'select  *  from SOFTWARE_CONFIG LIMIT 1 ') (JsonRpc.py|134)
[3] [Sep 05 15:02:44] Execution error: Backend configuration error: You have tried to execute a method, that will not work with filebackend. (JsonRpc.py|146)
[4] [Sep 05 15:02:44] Failed RPC on u'getRawData' with params [u'select  *  from SOFTWARE_CONFIG LIMIT 1 ']: <BackendConfigurationError(u'You have tried to execute a method, that will not work with filebackend.')> (statistics.py|418)
[5] [Sep 05 15:02:44] Application 'opsi config editor 4.0.7.1.3' on client 'IP' did not send cookie (workers.py|166)
[5] [Sep 05 15:02:44] New session created (session.py|77)
[5] [Sep 05 15:02:44] Authorization request from USER@IP (application: opsi config editor 4.0.7.1.3) (workers.py|196)
[5] [Sep 05 15:02:45] Modules file signature verified (customer: NAME) (MySQL.py|523)
[5] [Sep 05 15:02:46] -----> Executing: getPossibleMethods_listOfHashes() (JsonRpc.py|134)
[5] [Sep 05 15:02:46] -----> Executing: getOpsiInformation_hash() (JsonRpc.py|134)
[5] [Sep 05 15:02:46] -----> Executing: host_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:02:47] -----> Executing: configState_getObjects([]) (JsonRpc.py|134)

Das Log am Server bei einem Client mit Java 7:

Code: Alles auswählen

[5] [Sep 05 15:09:07] Application 'opsi config editor 4.0.7.1.3' on client 'IP' did not send cookie (workers.py|166)
[5] [Sep 05 15:09:07] New session created (session.py|77)
[5] [Sep 05 15:09:07] Authorization request from USER@IP (application: opsi config editor 4.0.7.1.3) (workers.py|196)
[5] [Sep 05 15:09:07] Modules file signature verified (customer: NAME) (MySQL.py|523)
[5] [Sep 05 15:09:08] -----> Executing: authenticated() (JsonRpc.py|134)
[5] [Sep 05 15:09:08] -----> Executing: getRawData(u'select  *  from SOFTWARE_CONFIG LIMIT 1 ') (JsonRpc.py|134)
[3] [Sep 05 15:09:08] Execution error: Backend configuration error: You have tried to execute a method, that will not work with filebackend. (JsonRpc.py|146)
[4] [Sep 05 15:09:08] Failed RPC on u'getRawData' with params [u'select  *  from SOFTWARE_CONFIG LIMIT 1 ']: <BackendConfigurationError(u'You have tried to execute a method, that will not work with filebackend.')> (statistics.py|418)
[5] [Sep 05 15:09:08] Application 'opsi config editor 4.0.7.1.3' on client 'IP' did not send cookie (workers.py|166)
[5] [Sep 05 15:09:08] New session created (session.py|77)
[5] [Sep 05 15:09:08] Authorization request from USER@IP (application: opsi config editor 4.0.7.1.3) (workers.py|196)
[5] [Sep 05 15:09:09] Modules file signature verified (customer: NAME) (MySQL.py|523)
[5] [Sep 05 15:09:10] -----> Executing: getPossibleMethods_listOfHashes() (JsonRpc.py|134)
[5] [Sep 05 15:09:10] -----> Executing: getOpsiInformation_hash() (JsonRpc.py|134)
[5] [Sep 05 15:09:10] -----> Executing: host_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:11] -----> Executing: configState_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:15] -----> Executing: config_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:15] -----> Executing: config_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:15] -----> Executing: authenticated() (JsonRpc.py|134)
[5] [Sep 05 15:09:15] -----> Executing: accessControl_userIsReadOnlyUser() (JsonRpc.py|134)
[5] [Sep 05 15:09:15] -----> Executing: config_updateObjects([<UnicodeConfig(id=u'product_sort_algorithm', description=u'', possibleValues=[u'algorithm1', u'algorithm2'], defaultValues=[], editable=False, multiValue=False)>, <UnicodeConfig(id=u'configed.license...) (JsonRpc.py|134)
[5] [Sep 05 15:09:16] -----> Executing: objectToGroup_getObjects() (JsonRpc.py|134)
[5] [Sep 05 15:09:16] -----> Executing: config_updateObjects(<UnicodeConfig(id=u'configed.host_displayfields', description=u'', possibleValues=[u'clientConnected', u'clientCreated', u'clientDescription', u'clientHardwareAddress', u'clientIPAddress', u'clientInv...) (JsonRpc.py|134)
[5] [Sep 05 15:09:16] -----> Executing: configState_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:20] -----> Executing: product_getObjects([u'productVersion', u'packageVersion', u'id', u'setupScript', u'updateScript', u'uninstallScript', u'alwaysScript', u'onceScript', u'customScript', u'userLoginScript', u'priority', u'advice', u'name',...) (JsonRpc.py|134)
[5] [Sep 05 15:09:21] -----> Executing: productOnDepot_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:21] -----> Executing: getProductOrdering(u'') (JsonRpc.py|134)
[5] [Sep 05 15:09:21] Using sort algorithm 'algorithm2' (10_opsi.conf|72)
[5] [Sep 05 15:09:22] Generating product sequence by algorithm 2: (SharedAlgorithm.py|686)
[4] [Sep 05 15:09:22] Dependency declaration between swaudit and isf-base contradicts priority declaration, will be ignored (SharedAlgorithm.py|734)
[5] [Sep 05 15:09:22] -----> Executing: productProperty_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:25] -----> Executing: getProductOrdering(u'han.isf.rwth-aachen.de') (JsonRpc.py|134)
[5] [Sep 05 15:09:25] Using sort algorithm 'algorithm2' (10_opsi.conf|72)
[5] [Sep 05 15:09:26] Generating product sequence by algorithm 2: (SharedAlgorithm.py|686)
[4] [Sep 05 15:09:26] Dependency declaration between swaudit and isf-base contradicts priority declaration, will be ignored (SharedAlgorithm.py|734)
[5] [Sep 05 15:09:26] -----> Executing: getDomain() (JsonRpc.py|134)
[5] [Sep 05 15:09:26] -----> Executing: getOpsiHWAuditConf(u'de_DE') (JsonRpc.py|134)
[5] [Sep 05 15:09:26] -----> Executing: productDependency_getObjects([]) (JsonRpc.py|134)
[5] [Sep 05 15:09:28] -----> Executing: auditSoftware_getHashes([u'name', u'version', u'subVersion', u'language', u'architecture', u'windowsSoftwareId']) (JsonRpc.py|134)

Grüße
Andreas

[r.roeder]

interessant, diese Logs sehen so aus, als würde die Kommunikation mit dem Service bei einem Java8-Client nicht funktionieren, aber bei einem Java7-Client schon. Startet dementsprechend der configed mit Java7 auch korrekt?

Grüße
Rupert