error: 42 - Error during HTTP (session/user) GET request.

[dariusbengfort]

Hallo, 

ich erhalte folgenden Fehler:

Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - [SERVER] Start
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - Initialize 26 service extensions
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - Initialize Redis cache backend
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - Create ClientRegsitration
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - [CLIENT REGISTRATION] Start
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - Update translations
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - Create Default Configurations
Mon May 13 2024 10:08:16 GMT+0200 (Central European Summer Time) - info: 42 - REDIS connecting
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - info: 42 - Found 91 configuration extensions
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - info: 42 - REDIS ready
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - info: 42 - REDIS: Subscribed to channel KIXFrontendNotify
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - error: 42 - Error during HTTP (session/user) GET request.
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - error: 42 - (401) Unauthorized User is not authorized. The requested operation cannot be executed.
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - error: 42 - An unhandledRejection occured:
Mon May 13 2024 10:08:17 GMT+0200 (Central European Summer Time) - error: 42 - [object Object]
Unhandled Rejection at: Promise Promise {
  <rejected> Error {
    Code: 'Unauthorized',
    Message: 'User is not authorized. The requested operation cannot be executed.',
    StatusCode: 401
  }
} reason: Error {
  Code: 'Unauthorized',
  Message: 'User is not authorized. The requested operation cannot be executed.',
  StatusCode: 401
}
Error {
  Code: 'Unauthorized',
  Message: 'User is not authorized. The requested operation cannot be executed.',
  StatusCode: 401
}

Dieser Fehler ist "über Nacht" aufgetreten. Ohne eine Veränderung an der Konfiguration. Das Frontend bleibt in der "Ladeansicht" aber es aufgrund des Fehlers ist es nicht erreichbar. 
Tickets kommen trotzdem noch an und werden verarbeitet. 

[Beatrice Müller]

Hallo,

lösche bitte einmal den API-Token (unter /var/lib/docker/volumes/kix_shared/_data/backend_api_token) und starte den Stack neu.
Sollte das nicht helfen, wäre die Build-Nummer noch interessant und die Logausgaben des Backends.

Viele Grüße
Beatrice

[Tamme17]

Hallo,

ich würde mich dem Thema anschließen. Bei mir ist es das gleiche Fehlerbild. Nach löschen des API Keys kommt zwar der Fehler nicht mehr, allerdings startet das Frontend hierbei auch nicht. Bei mir ist es sogar ein komplett neuer Server, frisch aufgesetzt, da der Plan war unseren Produktiv-Server umzuziehen. 
Interessant ist es, dass der Stack neu löschen der Volumes usw (also quasi einmal alles komplett neu beziehen) normal staret. Möchten man dann den Stack wieder starten, kommt das genannte Fehlerbild.
Wie bekomme ich die Build-Nummer ohne Web Interface heraus?

Viele Grüße.

[Beatrice Müller]

Hallo,

zur weiteren Analyse benötigen wir die Logs (FE, BE):

Code Auswählen Erweitern

docker logs kix-backend-1 > /tmp/BE.log 2>&1

Code Auswählen Erweitern

docker logs kix-frontend-1 > /tmp/FE.log 2>&1
Prüfe bitte außerdem den admin-User in der DB:

Code Auswählen Erweitern

docker exec -it kix-db-1 psql -h kix-db-1 -U kix -d kix -c "SELECT login FROM users WHERE id=1;"
Die Build-Nummer findest du mit folgendem Befehl raus:

Code Auswählen Erweitern

docker exec kix-backend-1 bash -c "cat /opt/kix/RELEASE"
Viele Grüße
Beatrice

[Tamme17]

Hallo,

hier die Ausgaben:

admin-user:

Code Auswählen Erweitern

root@rz2oelkix31:/opt/kix-on-premise/deploy/linux# docker exec -it kix-db-1 psql -h kix-db-1 -U kix -d kix -c "SELECT login FROM users WHERE id=1;"
Password for user kix:
 login
-------
 admin
(1 row)
Buildnummer:

Code Auswählen Erweitern

root@rz2oelkix31:/opt/kix-on-premise/deploy/linux# docker exec kix-backend-1 bash -c "cat /opt/kix/RELEASE"
PRODUCT = KIX
VERSION = 18
BUILDDATE = Fri, 19 Jan 2024 09:31:31 +0100
BUILDHOST = git.intra.cape-it.de
BUILDNUMBER = 1868
PATCHNUMBER = 1
Im Anhang sind die Logs vom Front- und Backend.

Viele Grüße.

[Beatrice Müller]

Im BE-Log ist ersichtlich, dass der Container als root läuft.

Sende mal bitte die Ausgabe von folgendem Befehl:

Code Auswählen Erweitern

docker exec kix-backend-1 bash -c "cat /etc/debian_version"

[Tamme17]

Hi,

hier die Ausgabe des Befehls:

Code Auswählen Erweitern

root@rz2oelkix31:/opt/kix-on-premise/deploy/linux# docker exec kix-backend-1 bash -c "cat /etc/debian_version"
10.13

[Beatrice Müller]

Wurde docker ggf. per snap installiert?

[Tamme17]

Nein docker wurde normal laut Anleitung installiert. --> https://docs.docker.com/engine/install/ubuntu/

[Beatrice Müller]

Kannst du einmal deine docker-compose.yml posten?

Am Anfang deines BE-Logs sieht man, dass der User mit ID 0 verwendet wird.
Hier müsste '110010100' stehen:

Code Auswählen Erweitern

etc/passwd
++ id -u
++ id -g
+ echo 'kix:x:110010100:0:dynamic uid:/tmp:/bin/false'
+ cat /etc/group
++ id -u
+ echo kix:x:110010100:
+ export NSS_WRAPPER_PASSWD=/tmp/passwd

[Tamme17]

Hi,

ja das stimmt, dass war noch wegen einer anderen Sache (https://forum.kixdesk.com/index.php?topic=12157.0), habe ich nun aber gefixt. Ich habe nocheinmal alles neu gezogen (volumes gelöscht, images neu bezogen und nochmal den kix-on-premises Ordner von Git gepulled) leider kommt der gleiche Fehler. 

Code Auswählen Erweitern

backend-1   |      Method: GET
backend-1   |    Resource: /session/user
backend-1   |    Duration: 21 ms
backend-1   |  HTTPStatus: 401 Unauthorized
backend-1   |        Code: Unauthorized
backend-1   |     Message: User is not authorized. The requested operation cannot be executed.
backend-1   |
backend-1   | 10.10.10.5 - - [16/May/2024:06:42:33 +0200] "GET /api/v1/session/user?include=Preferences,RoleIDs,Contact,DynamicFields HTTP/1.1" 401 103 56162
frontend-1  | Thu May 16 2024 06:42:33 GMT+0200 (Central European Summer Time) - error: 42 - Error during HTTP (session/user) GET request.
frontend-1  | Thu May 16 2024 06:42:33 GMT+0200 (Central European Summer Time) - error: 42 - (401) Unauthorized User is not authorized. The requested operation cannot be executed.
frontend-1  | Thu May 16 2024 06:42:33 GMT+0200 (Central European Summer Time) - error: 42 - An unhandledRejection occured:
frontend-1  | Thu May 16 2024 06:42:33 GMT+0200 (Central European Summer Time) - error: 42 - [object Object]
frontend-1  | Unhandled Rejection at: Promise Promise {
frontend-1  |   <rejected> Error {
frontend-1  |     Code: 'Unauthorized',
frontend-1  |     Message: 'User is not authorized. The requested operation cannot be executed.',
frontend-1  |     StatusCode: 401
frontend-1  |   }
frontend-1  | } reason: Error {
frontend-1  |   Code: 'Unauthorized',
frontend-1  |   Message: 'User is not authorized. The requested operation cannot be executed.',
frontend-1  |   StatusCode: 401
frontend-1  | }
frontend-1  | Error {
frontend-1  |   Code: 'Unauthorized',
frontend-1  |   Message: 'User is not authorized. The requested operation cannot be executed.',
frontend-1  |   StatusCode: 401
frontend-1  | }
Die docker-compose.yml ist nochmal im Anhang.

[Beatrice Müller]

Dann würde ich jetzt (nach den ganzen Änderungen) noch einmal beide Logs benötigen.

[Tamme17]

Hallo Beatrice,

im Anhang nochmals die Logs. Danke fürs kümmern.

[Beatrice Müller]

Das sieht aber schon besser aus. Jetzt bitte noch einmal die Umgebung durch starten.

[Tamme17]

So wirklich besser ist es allerdings nicht, da der Fehler immer noch da ist.
BE:

Code Auswählen Erweitern

API ERROR: ProcessID: 96 Time: 2024-05-17 07:19:04

     Method: GET
   Resource: /session
   Duration: 30 ms
 HTTPStatus: 403 Forbidden
       Code: Authorization.NoHeader
    Message: No "Authorization" header found.
FE:

Code Auswählen Erweitern

Fri May 17 2024 07:19:06 GMT+0200 (Central European Summer Time) - ␛[31merror␛[39m: 42 - [object Object]
Unhandled Rejection at: Promise Promise {
  <rejected> Error {
    Code: 'Unauthorized',
    Message: 'User is not authorized. The requested operation cannot be executed.',
    StatusCode: 401
  }