opsiconfd startet nicht mehr nach Update auf 4.3.12.8

[Decision]

Hallo zusammen,

abei die Ausgangslage:

Ein Config-Server mit 26 angebundenen Depot alles Debian 12.5.
Ich habe zuerst den Config-Server geupdatet, dann alle Depot.
Bei sieben Depots starte nach dem Neustart der opsiconfd nicht mehr automatisch.
Wenn ich über systemctl start opsiconfd starte, funktioniert es.
Nach einem Neustart ist opsiconfd wieder nicht gestartet.

Ich habe testweise beim run-as user statt dem User opsiconfd den root user hinterlegt. Das funktioniert.
Wenn es wieder zurückgesetllt wird, funktioniert es nicht mehr.

opsiconfd.service - opsi server daemon
Loaded: loaded (/lib/systemd/system/opsiconfd.service; enabled; preset: enabled)
Active: failed (Result: exit-code) since Sun 2024-04-21 19:55:17 CEST; 18h ago
Duration: 9.354s
Process: 659 ExecStartPre=/usr/lib/opsiconfd/opsiconfd-pre-start (code=exited, status=0/SUCCESS)
Process: 692 ExecStart=/usr/bin/opsiconfd start --log-level-stderr=0 (code=exited, status=1/FAILURE)
Main PID: 692 (code=exited, status=1/FAILURE)
CPU: 3.240s

Apr 21 19:55:08 systemd[1]: Starting opsiconfd.service - opsi server daemon...
Apr 21 19:55:08 systemd[1]: Started opsiconfd.service - opsi server daemon.
Apr 21 19:55:17 opsiconfd[692]: Traceback (most recent call last):
Apr 21 19:55:17 opsiconfd[692]: File "run-opsiconfd.py", line 12, in <module>
Apr 21 19:55:17 opsiconfd[692]: File "opsiconfd/main/__init__.py", line 65, in main
Apr 21 19:55:17 opsiconfd[692]: File "opsiconfd/main/opsiconfd.py", line 41, in opsiconfd_main
Apr 21 19:55:17 opsiconfd[692]: RuntimeError: Opsiconfd manager process already running (pid 1039)
Apr 21 19:55:17 systemd[1]: opsiconfd.service: Main process exited, code=exited, status=1/FAILURE
Apr 21 19:55:17 systemd[1]: opsiconfd.service: Failed with result 'exit-code'.
Apr 21 19:55:17 systemd[1]: opsiconfd.service: Consumed 3.240s CPU time.

Hat jemand eine Idee, wo das Problem liegen könnte?

Beste Grüße,
Michael

[j.schneider]

Das könnte daran liegen, dass der Benutzer "opsiconfd" kein lokaler Benutzer ist oder die Gruppen-Zugehörigkeiten nicht stimmen.
Was gibt der Health-Check aus?

Code: Alles auswählen

opsiconfd health-check

[Decision]

Hallo und danke für die schnelle Rückmeldung.

Der Health-Check gibt keine Fehler aus, abgesehen von ein paar Product outdated on Client.

Der Benutzer opsiconf ist lokal und Mitglied folgender Gruppen: opsifileadmins shadow opsiadmin

Passt das so oder muss der in weiteren Gruppen sein?

Beste Grüße,
Michael

[j.schneider]

Hallo,

nein, das passt so.
Bitte einmal direkt nach dem Start in die /var/log/opsi/opsiconfd/opsiconfd.log schauen.

Grüße
Jan Schneider

[Decision]

Leider steht im Log garnichts nach dem Neustart.
Das Depot wird Abends neu gestartet.
Die letzten Logeinträge (Loglevel habe ich am Montag auf 7 gesetzt) sind vor dem Reboot:

[6] [2024-04-23 21:31:58.917] [ ] Application shutdown complete. (on.py:76)
[6] [2024-04-23 21:31:58.917] [ ] Finished server process [1925] (server.py:87)
[7] [2024-04-23 21:32:02.291] [ ] Sending ping (_logging.py:85)
[7] [2024-04-23 21:32:02.319] [ ] Pong message received (opsiservice.py:1548)
[5] [2024-04-23 21:32:02.770] [ ] All workers stopped (manager.py:210)
[5] [2024-04-23 21:32:02.770] [ ] Disconnecting from opsi messagebus (opsiservice.py:1780)
[6] [2024-04-23 21:32:02.798] [ ] Websocket closed with status_code=None and message=None (opsiservice.py:1497)
[7] [2024-04-23 21:32:02.800] [ ] Resetting dropped connection: configserver (connectionpool.py:292)
[7] [2024-04-23 21:32:02.898] [ ] https://configserver:4447 "POST /session/logout HTTP/1.1" 200 17 (connectionpool.py:549)

Wo könnte ich noch ansetzen bzw. welches Log könnten ich noch prüfen?

[j.schneider]

Laut syslog startet der opsiconfd nicht, weil bereits ein anderer opsiconfd-Prozess läuft:

Code: Alles auswählen

RuntimeError: Opsiconfd manager process already running (pid 1039)

Interessant wäre zu wissen, welcher Prozess das ist (im Beispiel PID 1039).

[Decision]

Ich habe gerade geprüft. Der angegebene Prozess (aktuell PID1083) scheint aktuell nicht zu laufen:

Apr 23 21:32:24 bsjk-opsi opsiconfd[760]: RuntimeError: Opsiconfd manager process already running (pid 1083)
Apr 23 21:32:25 systemd[1]: opsiconfd.service: Main process exited, code=exited, status=1/FAILURE
Apr 23 21:32:25 systemd[1]: opsiconfd.service: Failed with result 'exit-code'.

root 1053 0.0 1.9 325804 58392 ? Sl Apr23 0:38 ./opsipxeconfd start
postfix 1892 0.0 0.2 43044 7124 ? S 07:32 0:00 pickup -l -t unix -u -c
root 1909 0.0 0.0 0 0 ? I 07:33 0:00 [kworker/1:2-events]

[j.schneider]

Über Ferndiagnose wird es an dieser Stelle schwierig.
Im Rahmen eines Support-Vertrages können wir uns das auf dem System anschauen.

Grüße
Jan Schneider

[Decision]

Danke erstmal für die Unterstützung.

Die Updates von heute beheben bei fast allen Depots das Problem.

Bei den zwei Depots, wo es nicht auf Anhieb klappte, haben wir als Workaround folgendes gebaut:

In /lib/systemd/system/opsiconfd.service unter [Service] folgendes ergänzt:
Restart=always