+ export KIX_HOME=/opt/kix + KIX_HOME=/opt/kix + export PERL_JSON_BACKEND=Cpanel::JSON::XS + PERL_JSON_BACKEND=Cpanel::JSON::XS + export PLACK_ENV=deployment + PLACK_ENV=deployment + cat /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 + NSS_WRAPPER_PASSWD=/tmp/passwd + export NSS_WRAPPER_GROUP=/tmp/group + NSS_WRAPPER_GROUP=/tmp/group + export LD_PRELOAD=libnss_wrapper.so + LD_PRELOAD=libnss_wrapper.so + '[' -n '' ']' + service dbus start dbus: unrecognized service + service systemd-timesyncd start systemd-timesyncd: unrecognized service + check_and_prepare_data_dirs + for DIR in articles asset-images config logs spool + mkdir -pv /mnt/data/articles + case ${DIR} in + for DIR in articles asset-images config logs spool + mkdir -pv /mnt/data/asset-images + case ${DIR} in + rm -rf /opt/kix/var/ITSMConfigItem + ln -sv /mnt/data/asset-images /opt/kix/var/ITSMConfigItem '/opt/kix/var/ITSMConfigItem' -> '/mnt/data/asset-images' + for DIR in articles asset-images config logs spool + mkdir -pv /mnt/data/config + case ${DIR} in + mv /opt/kix/config/01main.dist /opt/kix/config/installation /mnt/data/config mv: inter-device move failed: '/opt/kix/config/installation' to '/mnt/data/config/installation'; unable to remove target: Directory not empty + rm -rf /opt/kix/config + ln -sv /mnt/data/config /opt/kix/config '/opt/kix/config' -> '/mnt/data/config' + for DIR in articles asset-images config logs spool + mkdir -pv /mnt/data/logs + case ${DIR} in + rm -rf /opt/kix/var/log + ln -sv /mnt/data/logs /opt/kix/var/log '/opt/kix/var/log' -> '/mnt/data/logs' + for DIR in articles asset-images config logs spool + mkdir -pv /mnt/data/spool + case ${DIR} in + rm -rf /opt/kix/var/spool + ln -sv /mnt/data/spool /opt/kix/var/spool '/opt/kix/var/spool' -> '/mnt/data/spool' + waitfor redis-cache 10 'redis-cli -h redis ping' + '[' postgresql == postgresql ']' + waitfor database 10 'export PGPASSWORD=$KIXDB_PASSWORD;export PGOPTIONS=--client-min-messages=warning;psql -t -q -h ${KIXDB_HOST} -U ${KIXDB_USER} ${KIXDB_DATABASE} -c "\dt"' + '[' postgresql == mysql ']' ++ grep BUILDNUMBER /opt/kix/RELEASE ++ cut -d= -f2 ++ sed -e 's/ *//g' + BUILDNO=1974 + '[' '!' -f /opt/kix/config/01main ']' + case "$KIXDB_DBMS" in + install_db_postgresql + FRESH_INSTALL=1 ++ export PGPASSWORD=kix ++ PGPASSWORD=kix ++ export PGOPTIONS=--client-min-messages=warning ++ PGOPTIONS=--client-min-messages=warning ++ psql -t -q -h db -U kix kix -c 'SELECT count(*) FROM valid' ++ sed -e 's/ //g' + VALID_COUNT=3 + '[' 3 == 3 ']' + FRESH_INSTALL=0 + '[' 0 -eq 1 ']' + INDEX_SQL='CREATE EXTENSION IF NOT EXISTS pg_trgm; CREATE INDEX IF NOT EXISTS trgm_contact_email ON contact USING gin (email gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_firstname ON contact USING gin (firstname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_lastname ON contact USING gin (lastname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_title ON contact USING gin (title gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_phone ON contact USING gin (phone gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_fax ON contact USING gin (fax gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_mobile ON contact USING gin (mobile gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_street ON contact USING gin (street gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_zip ON contact USING gin (zip gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_city ON contact USING gin (city gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_country ON contact USING gin (country gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_email ON contact USING gin (email gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_firstname ON contact USING gin (firstname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_lastname ON contact USING gin (lastname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_name ON organisation USING gin (name gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_number ON organisation USING gin (number gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_street ON organisation USING gin (street gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_zip ON organisation USING gin (zip gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_city ON organisation USING gin (city gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_country ON organisation USING gin (country gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_url ON organisation USING gin (url gin_trgm_ops);' + export PGPASSWORD=kix + PGPASSWORD=kix + export PGOPTIONS=--client-min-messages=warning + PGOPTIONS=--client-min-messages=warning + psql -q -h db -U kix kix -c 'CREATE EXTENSION IF NOT EXISTS pg_trgm; CREATE INDEX IF NOT EXISTS trgm_contact_email ON contact USING gin (email gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_firstname ON contact USING gin (firstname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_lastname ON contact USING gin (lastname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_title ON contact USING gin (title gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_phone ON contact USING gin (phone gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_fax ON contact USING gin (fax gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_mobile ON contact USING gin (mobile gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_street ON contact USING gin (street gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_zip ON contact USING gin (zip gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_city ON contact USING gin (city gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_country ON contact USING gin (country gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_email ON contact USING gin (email gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_firstname ON contact USING gin (firstname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_contact_lastname ON contact USING gin (lastname gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_name ON organisation USING gin (name gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_number ON organisation USING gin (number gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_street ON organisation USING gin (street gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_zip ON organisation USING gin (zip gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_city ON organisation USING gin (city gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_country ON organisation USING gin (country gin_trgm_ops); CREATE INDEX IF NOT EXISTS trgm_organisation_url ON organisation USING gin (url gin_trgm_ops);' rebuilding config + echo rebuilding config + /opt/kix/bin/kix.Console.pl Maint::Config::Rebuild --allow-root Rebuilding the system configuration... rebuilt 1499 config options (created 0, updated 36, skipped 1463, failed 0) Done. deleting caches + echo deleting caches + /opt/kix/bin/kix.Console.pl Maint::Cache::Delete --allow-root Deleting cache... Done. ++ cat /opt/kix/config/installation/framework + INSTALLED_BUILDNO=1974 + '[' -z 1974 ']' + /opt/kix/bin/kix.Console.pl Admin::Installation::Update --source-build 1974 --target-build 1974 Updating installation... Done. + find /opt/kix/config/ -print0 -type l '!' -exec test -f '{}' ';' -exec rm -fv '{}' ';' + ln -s /opt/kix/conf.d/example.conf /opt/kix/config/ ln: failed to create symbolic link '/opt/kix/config/example.conf': File exists + '[' '!' -s /mnt/shared/backend_api_token ']' ++ cat /mnt/shared/backend_api_token + BACKEND_API_TOKEN=eyJhbGciOiJIUzI1NiJ9.eyJWYWxpZFVudGlsIjoiOTk5OS0xMi0zMSAyMzo1OTo1OSIsIlVzZXJJRCI6MSwiSWdub3JlTWF4SWRsZVRpbWUiOjEsIlVzZXJUeXBlIjoiQWdlbnQiLCJWYWxpZFVudGlsVGltZVVuaXgiOjI1MzQwMjI5NzE5OSwiRGVzY3JpcHRpb24iOiJmcm9udGVuZCBBUEkgdG9rZW4iLCJBbGxvd2VkT3BlcmF0aW9ucyI6W10sIkRlbmllZE9wZXJhdGlvbnMiOltdLCJDcmVhdGVUaW1lVW5peCI6MTczMjg4MTQ3MiwiVG9rZW5UeXBlIjoiQWNjZXNzVG9rZW4iLCJSZW1vdGVJUCI6IjAuMC4wLjAifQ.evfhMxXJtj3PiqJdd0H84REAKmx2Upr8FJB-5_T2bWI /opt/kix/config//opt/kix/config/02secret/opt/kix/config/example.conf/opt/kix/config/installation/opt/kix/config/installation/KIXConnectWebservice/opt/kix/config/installation/.gitkeep/opt/kix/config/installation/KIXConnect/opt/kix/config/installation/framework/opt/kix/config/installation/KIXPro/opt/kix/config/01main/opt/kix/config/01main.distrebuilding config + '[' -z eyJhbGciOiJIUzI1NiJ9.eyJWYWxpZFVudGlsIjoiOTk5OS0xMi0zMSAyMzo1OTo1OSIsIlVzZXJJRCI6MSwiSWdub3JlTWF4SWRsZVRpbWUiOjEsIlVzZXJUeXBlIjoiQWdlbnQiLCJWYWxpZFVudGlsVGltZVVuaXgiOjI1MzQwMjI5NzE5OSwiRGVzY3JpcHRpb24iOiJmcm9udGVuZCBBUEkgdG9rZW4iLCJBbGxvd2VkT3BlcmF0aW9ucyI6W10sIkRlbmllZE9wZXJhdGlvbnMiOltdLCJDcmVhdGVUaW1lVW5peCI6MTczMjg4MTQ3MiwiVG9rZW5UeXBlIjoiQWNjZXNzVG9rZW4iLCJSZW1vdGVJUCI6IjAuMC4wLjAifQ.evfhMxXJtj3PiqJdd0H84REAKmx2Upr8FJB-5_T2bWI ']' + echo rebuilding config + /opt/kix/bin/kix.Console.pl Maint::Config::Rebuild --allow-root Rebuilding the system configuration... rebuilt 1499 config options (created 0, updated 36, skipped 1463, failed 0) Done. + echo deleting caches deleting caches + /opt/kix/bin/kix.Console.pl Maint::Cache::Delete --allow-root Deleting cache... Done. + echo 'building ssl certificates' building ssl certificates + /opt/kix/bin/kix.Console.pl Maint::Certificate::Build --allow-root building certificates... Done. + /opt/kix/bin/kix.Console.pl Admin::Installation::GenerateAPIWebServiceDefinition --api-version v1 --output-file /opt/kix/var/API.yml Generating web service definition file for "v1"... Done. ++ /opt/kix/bin/kix.Console.pl Admin::WebService::List ++ grep v1 + CHECK_WEBSERVICE=' v1 (1)' + '[' -z ' v1 (1)' ']' + /opt/kix/bin/kix.Console.pl Admin::WebService::Update --name v1 --source-path /opt/kix/var/API.yml Updating web service... Done. + find /opt/kix/plugins -name '*' -ipath '*/autostart/*' -exec ln -s '{}' /opt/kix/autostart ';' + . /etc/profile +++ id -u ++ '[' 110010100 -eq 0 ']' ++ PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games ++ export PATH ++ '[' '' ']' ++ '[' -d /etc/profile.d ']' ++ for i in /etc/profile.d/*.sh ++ '[' -r /etc/profile.d/vte-2.91.sh ']' ++ . /etc/profile.d/vte-2.91.sh +++ '[' -n '5.2.15(1)-release' -o -n '' ']' +++ [[ hxB == *i* ]] +++ return 0 ++ unset i ++ export PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games:/opt/mssql-tools/bin ++ PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games:/opt/mssql-tools/bin + export http_proxy= + http_proxy= + export https_proxy= + https_proxy= + trap cleanup SIGINT SIGTERM + rm -rf '/opt/kix/var/run/*' + export LANG=C.UTF-8 + LANG=C.UTF-8 starting daemon + echo starting daemon starting backend server + echo starting backend server + export PERL5LIB=/opt/kix/Kernel/cpan-lib: + PERL5LIB=/opt/kix/Kernel/cpan-lib: + export IPV=4 + IPV=4 + plackup -s Starman -p 8080 /opt/kix/bin/server/app.psgi + /opt/kix/bin/kix.Daemon.pl start kix.Daemon.pl - the KIX daemon Daemon started Updating web service... Done. Updating web service... Done. Updating icon database... Imported 203/203 icons. Done. Updating installation... Done. Updating service catalog... Done. Starting rebuild of ticket index... Done. Updating translations... importing from directory /opt/kix/locale/FieldAgentApp importing de.po...200/201 importing en.po...28/28 importing from directory /opt/kix/locale/SSP importing de.po...0/69 importing from directory /opt/kix/locale importing de.po...664/1368 importing en.po...76/1307 Done. Updating translations... importing from directory /opt/kix/plugins/KIXConnect/locale importing de.po...61/78 importing from directory /opt/kix/plugins/KIXPro/locale importing de.po...359/488 importing en.po...18/474 Done. Updating translations... importing from directory /opt/kix/locale/FieldAgentApp importing de.po...200/201 importing en.po...28/28 Done. Start create CSV-mapping... Create mapping for ClassID 8 ... 2024/12/12-11:32:45 Starman::Server (type Net::Server::PreFork) starting! pid(69) Binding to TCP port 8080 on host * with IPv4 Setting gid to "0 0 0" Create mapping for ClassID 7 ... Create mapping for ClassID 5 ... Create mapping for ClassID 6 ... Create mapping for ClassID 4 ... Create mapping for ClassID 92 ... Create mapping for ClassID 10 ... Create mapping for ClassID 9 ... Done. API ERROR: ProcessID: 86 Time: 2024-12-12 11:32:47 Method: GET Resource: /session Duration: 12 ms HTTPStatus: 403 Forbidden Code: Authorization.NoHeader Message: No "Authorization" header found. API ERROR: ProcessID: 87 Time: 2024-12-12 11:32:47 Method: GET Resource: /session Duration: 5 ms HTTPStatus: 403 Forbidden Code: Authorization.NoHeader Message: No "Authorization" header found. 127.0.0.1 - - [12/Dec/2024:11:32:47 +0100] "GET /api/v1/session HTTP/1.1" 403 80 127744 172.18.0.5 - - [12/Dec/2024:11:32:47 +0100] "GET /api/v1/session HTTP/1.1" 403 80 27166 [Thu Dec 12 11:32:49 2024] plackup: DBD::Pg::st execute failed: server closed the connection unexpectedly [Thu Dec 12 11:32:49 2024] plackup: This probably means the server terminated abnormally [Thu Dec 12 11:32:49 2024] plackup: before or while processing the request. at /opt/kix/Kernel/System/DB.pm line 791. [Thu Dec 12 11:32:49 2024][Error][Kernel::System::Token::ValidateToken][88] server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request., SQL: 'SELECT token, last_request_time FROM token WHERE token = ?' ERROR: API-78 Perl: 5.36.0 OS: linux Time: Thu Dec 12 11:32:49 2024 Message: server closed the connection unexpectedly This probably means the server terminated abnormally before or while processing the request., SQL: 'SELECT token, last_request_time FROM token WHERE token = ?' RemoteAddress: 172.18.0.5 RequestURI: /session/user?include=Preferences,RoleIDs,Contact,DynamicFields Traceback (88): Module: Kernel::System::Token::ValidateToken Line: 88 Module: Kernel::API::Provider::REST::CheckAuthorization Line: 75 Module: Kernel::API::Provider::Run Line: 196 Module: (eval) Line: 72 Module: Plack::Sandbox::_2fopt_2fkix_2fbin_2fserver_2fapp_2epsgi::__ANON__ Line: 70 Module: CGI::Emulate::PSGI::__ANON__ (v0.23) Line: 30 Module: Plack::Middleware::StackTrace::try {...} Line: 38 Module: (eval) (v0.31) Line: 100 Module: Try::Tiny::try (v0.31) Line: 93 Module: Plack::Middleware::StackTrace::call Line: 42 Module: Plack::Component::__ANON__ Line: 50 Module: Plack::Middleware::AccessLog::Timed::call Line: 17 Module: Plack::Component::__ANON__ Line: 50 Module: Plack::Middleware::Deflater::call (v0.12) Line: 20 Module: Plack::Component::__ANON__ Line: 50 Module: Plack::Middleware::Refresh::call Line: 23 Module: Plack::Component::__ANON__ Line: 50 Module: (eval) Line: 145 Module: Plack::Util::run_app Line: 145 Module: Starman::Server::dispatch_request Line: 195 Module: Starman::Server::process_request Line: 299 Module: Net::Server::run_client_connection (v2.013) Line: 72 Module: (eval) Line: 226 Module: Net::Server::PreFork::run_child Line: 226 Module: Net::Server::PreFork::run_n_children Line: 184 Module: Net::Server::PreFork::loop Line: 107 Module: Net::Server::run (v2.013) Line: 58 Module: Starman::Server::run Line: 106 Module: Plack::Handler::Starman::run Line: 25 Module: Plack::Loader::run Line: 84 [Thu Dec 12 11:32:49 2024] plackup: DBD::Pg::st fetchrow_array failed: no statement executing at /opt/kix/Kernel/System/DB.pm line 859. API ERROR: ProcessID: 88 Time: 2024-12-12 11:32:49 Method: GET Resource: /session/user Duration: 10 ms HTTPStatus: 401 Unauthorized Code: Unauthorized Message: User is not authorized. The requested operation cannot be executed. 172.18.0.5 - - [12/Dec/2024:11:32:49 +0100] "GET /api/v1/session/user?include=Preferences,RoleIDs,Contact,DynamicFields HTTP/1.1" 401 103 29293 API ERROR: ProcessID: 89 Time: 2024-12-12 11:33:48 Method: GET Resource: /session Duration: 5 ms HTTPStatus: 403 Forbidden Code: Authorization.NoHeader Message: No "Authorization" header found. 127.0.0.1 - - [12/Dec/2024:11:33:48 +0100] "GET /api/v1/session HTTP/1.1" 403 80 26963 172.18.0.5 - - [12/Dec/2024:11:33:48 +0100] "GET /api/v1/news HTTP/1.1" 200 11 65949 172.18.0.5 - - [12/Dec/2024:11:33:48 +0100] "GET /api/v1/news?filter=%7B%22News%22:%7B%22AND%22:[%7B%22Field%22:%22ShowInLoginContext%22,%22Operator%22:%22GTE%22,%22Type%22:%22NUMERIC%22,%22Value%22:2%7D]%7D%7D&search=%7B%22News%22:%7B%22AND%22:[%7B%22Field%22:%22ShowInLoginContext%22,%22Operator%22:%22GTE%22,%22Type%22:%22NUMERIC%22,%22Value%22:2%7D]%7D%7D HTTP/1.1" 200 11 80667