Error Messages after Installation!

Infos:

  • Used Zammad version: 5.0.x & 5.3.x (latest stable)
  • Used Zammad installation type: source & package
  • Operating system: Debian 11, Apache 2.4, Mysql
  • Browser + version: Chrome 107 & MS Edge

Expected behavior:

Reaching the “First Steps Landing Page” after a fresh Installation.
*

Actual behavior:

I began with the Package Installation of the latest Version as described on “docs.zammad”.
The Result was an Error Message 500. “Service not available”.
After i´ve changed to Version 5.0.3 i can see the first Page but it´s impossible to create an Admin Account. Error Message “Error XXXXX please contact your Administrator”

Production LOG
I, [2022-11-24T10:59:17.687810 #40517-173560] INFO – : Rendering inline template
I, [2022-11-24T10:59:17.688854 #40517-173560] INFO – : Rendered inline template (Duration: 0.9ms | Allocations: 267)
I, [2022-11-24T10:59:17.692532 #40517-173560] INFO – : Completed 404 Not Found in 14ms (Views: 1.7ms | ActiveRecord: 2.4ms | Allocations: 1889)
I, [2022-11-24T10:59:18.028458 #40517-173700] INFO – : Started POST “/api/v1/signshow” for 31.18.249.204 at 2022-11-24 10:59:18 +0100
I, [2022-11-24T10:59:18.043812 #40517-173700] INFO – : Processing by SessionsController#show as JSON
I, [2022-11-24T10:59:18.043903 #40517-173700] INFO – : Parameters: {“fingerprint”=>“1609073090”}
I, [2022-11-24T10:59:18.133317 #40517-173700] INFO – : Completed 200 OK in 89ms (Views: 15.6ms | ActiveRecord: 31.8ms | Allocations: 23961)
I, [2022-11-24T10:59:18.185647 #40517-173700] INFO – : Started GET “/api/v1/translations/lang/de-de?=1669283959220" for 31.18.249.204 at 2022-11-24 10:59:18 +0100
I, [2022-11-24T10:59:18.208917 #40517-173700] INFO – : Processing by TranslationsController#lang as JSON
I, [2022-11-24T10:59:18.209002 #40517-173700] INFO – : Parameters: {"
”=>“1669283959220”, “locale”=>“de-de”}
I, [2022-11-24T10:59:18.222200 #40517-173700] INFO – : Completed 200 OK in 13ms (Views: 0.2ms | ActiveRecord: 5.3ms | Allocations: 2760)
I, [2022-11-24T10:59:18.317620 #40517-173700] INFO – : Started GET “/api/v1/getting_started?=1669283959221" for 31.18.249.204 at 2022-11-24 10:59:18 +0100
I, [2022-11-24T10:59:18.320274 #40517-173840] INFO – : Started GET “/fonts/FiraSans-Regular.ttf” for 31.18.249.204 at 2022-11-24 10:59:18 +0100
I, [2022-11-24T10:59:18.337249 #40517-173700] INFO – : Processing by GettingStartedController#index as JSON
I, [2022-11-24T10:59:18.337393 #40517-173700] INFO – : Parameters: {"
”=>“1669283959221”}
I, [2022-11-24T10:59:18.362867 #40517-173840] INFO – : Processing by ErrorsController#routing as TTF
I, [2022-11-24T10:59:18.362966 #40517-173840] INFO – : Parameters: {“a”=>“fonts/FiraSans-Regular”}
E, [2022-11-24T10:59:18.380067 #40517-173840] ERROR – : No route matches [GET] /fonts/FiraSans-Regular.ttf (ActionController::RoutingError)

I, [2022-11-24T10:59:18.380746 #40517-173840] INFO – : Rendering inline template
I, [2022-11-24T10:59:18.381487 #40517-173840] INFO – : Rendered inline template (Duration: 0.7ms | Allocations: 268)
I, [2022-11-24T10:59:18.383724 #40517-173840] INFO – : Completed 404 Not Found in 21ms (Views: 1.1ms | ActiveRecord: 10.8ms | Allocations: 4133)
I, [2022-11-24T10:59:18.386882 #40517-173700] INFO – : Completed 200 OK in 49ms (Views: 0.3ms | ActiveRecord: 13.4ms | Allocations: 11238)
I, [2022-11-24T10:59:24.561610 #40517-173060] INFO – : Started GET “/api/v1/getting_started?=1669283959222" for 31.18.249.204 at 2022-11-24 10:59:24 +0100
I, [2022-11-24T10:59:24.584498 #40517-173060] INFO – : Processing by GettingStartedController#index as JSON
I, [2022-11-24T10:59:24.584613 #40517-173060] INFO – : Parameters: {"
”=>“1669283959222”}
I, [2022-11-24T10:59:24.595571 #40517-173060] INFO – : Completed 200 OK in 11ms (Views: 0.2ms | ActiveRecord: 2.7ms | Allocations: 2515)
I, [2022-11-24T10:59:54.427772 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T10:59:58.917562 #40517-173420] INFO – : Started POST “/api/v1/users” for 31.18.249.204 at 2022-11-24 10:59:58 +0100
I, [2022-11-24T10:59:58.940836 #40517-173420] INFO – : Processing by UsersController#create as JSON
I, [2022-11-24T10:59:58.940972 #40517-173420] INFO – : Parameters: {“login”=>“Skydiver66”, “firstname”=>“Meier”, “lastname”=>“Test”, “email”=>“skydiver66@gmx.net”, “organization_id”=>“”, “role_ids”=>[], “id”=>“c-0”}
E, [2022-11-24T10:59:59.083386 #40517-173420] ERROR – : Couldn’t find User with ‘id’=1 (ActiveRecord::RecordNotFound)
app/models/concerns/checks_core_workflow.rb:25:in validate_workflows' app/controllers/users_controller.rb:1044:in block in create_admin’
lib/user_info.rb:23:in ensure_current_user_id' app/controllers/users_controller.rb:1043:in create_admin’
app/controllers/users_controller.rb:100:in create' app/controllers/application_controller/has_download.rb:21:in block (4 levels) in module:HasDownload
app/controllers/application_controller/has_download.rb:20:in block (3 levels) in <module:HasDownload>' app/controllers/application_controller/has_download.rb:19:in block (2 levels) in module:HasDownload
app/controllers/application_controller/handles_transitions.rb:16:in handle_transaction' I, [2022-11-24T10:59:59.086070 #40517-173420] INFO -- : Completed 404 Not Found in 145ms (Views: 0.4ms | ActiveRecord: 16.1ms | Allocations: 35040) I, [2022-11-24T11:00:16.890507 #40517-173560] INFO -- : Started GET "/api/v1/getting_started?_=1669283959223" for 31.18.249.204 at 2022-11-24 11:00:16 +0100 I, [2022-11-24T11:00:16.910049 #40517-173560] INFO -- : Processing by GettingStartedController#index as JSON I, [2022-11-24T11:00:16.910130 #40517-173560] INFO -- : Parameters: {"_"=>"1669283959223"} I, [2022-11-24T11:00:16.918828 #40517-173560] INFO -- : Completed 200 OK in 9ms (Views: 0.2ms | ActiveRecord: 2.1ms | Allocations: 2579) I, [2022-11-24T11:00:19.207696 #40517-173840] INFO -- : Started GET "/api/v1/getting_started?_=1669283959224" for 31.18.249.204 at 2022-11-24 11:00:19 +0100 I, [2022-11-24T11:00:19.230125 #40517-173840] INFO -- : Processing by GettingStartedController#index as JSON I, [2022-11-24T11:00:19.230257 #40517-173840] INFO -- : Parameters: {"_"=>"1669283959224"} I, [2022-11-24T11:00:19.244457 #40517-173840] INFO -- : Completed 200 OK in 14ms (Views: 0.3ms | ActiveRecord: 3.2ms | Allocations: 2515) I, [2022-11-24T11:00:24.748966 #40517-173700] INFO -- : Started GET "/api/v1/getting_started?_=1669283959225" for 31.18.249.204 at 2022-11-24 11:00:24 +0100 I, [2022-11-24T11:00:24.771208 #40517-173700] INFO -- : Processing by GettingStartedController#index as JSON I, [2022-11-24T11:00:24.771373 #40517-173700] INFO -- : Parameters: {"_"=>"1669283959225"} I, [2022-11-24T11:00:24.787203 #40517-173700] INFO -- : Completed 200 OK in 16ms (Views: 0.3ms | ActiveRecord: 3.1ms | Allocations: 2515) I, [2022-11-24T11:00:29.254925 #40517-173060] INFO -- : Started GET "/api/v1/getting_started?_=1669283959226" for 31.18.249.204 at 2022-11-24 11:00:29 +0100 I, [2022-11-24T11:00:29.276406 #40517-173060] INFO -- : Processing by GettingStartedController#index as JSON I, [2022-11-24T11:00:29.276605 #40517-173060] INFO -- : Parameters: {"_"=>"1669283959226"} I, [2022-11-24T11:00:29.287908 #40517-173060] INFO -- : Completed 200 OK in 11ms (Views: 0.2ms | ActiveRecord: 2.6ms | Allocations: 2515) I, [2022-11-24T11:00:54.495715 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:01:54.523770 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:02:54.555608 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:03:54.567717 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:04:54.631658 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:05:54.695748 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:06:54.724431 #40524-5220] INFO -- : Scheduler running... I, [2022-11-24T11:07:24.887705 #40517-173420] INFO -- : Started CONNECT "/" for 173.214.175.178 at 2022-11-24 11:07:24 +0100 F, [2022-11-24T11:07:24.909886 #40517-173420] FATAL -- : [493eccc5-67a8-4be5-8924-045d6afe520a] ActionController::RoutingError (No route matches [CONNECT] "/"): [493eccc5-67a8-4be5-8924-045d6afe520a] [493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/debug_exceptions.rb:36:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/show_exceptions.rb:33:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/rack/logger.rb:37:in call_app’
[493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/rack/logger.rb:26:in block in call' [493eccc5-67a8-4be5-8924-045d6afe520a] activesupport (6.0.4.4) lib/active_support/tagged_logging.rb:80:in block in tagged’
[493eccc5-67a8-4be5-8924-045d6afe520a] activesupport (6.0.4.4) lib/active_support/tagged_logging.rb:28:in tagged' [493eccc5-67a8-4be5-8924-045d6afe520a] activesupport (6.0.4.4) lib/active_support/tagged_logging.rb:80:in tagged’
[493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/rack/logger.rb:26:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/remote_ip.rb:81:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/request_id.rb:27:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] rack (2.2.3) lib/rack/method_override.rb:24:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] rack (2.2.3) lib/rack/runtime.rb:22:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] activesupport (6.0.4.4) lib/active_support/cache/strategy/local_cache_middleware.rb:29:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/executor.rb:14:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/static.rb:126:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] rack (2.2.3) lib/rack/sendfile.rb:110:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] actionpack (6.0.4.4) lib/action_dispatch/middleware/host_authorization.rb:97:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/engine.rb:527:in call' [493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/railtie.rb:190:in public_send’
[493eccc5-67a8-4be5-8924-045d6afe520a] railties (6.0.4.4) lib/rails/railtie.rb:190:in method_missing' [493eccc5-67a8-4be5-8924-045d6afe520a] puma (4.3.10) lib/puma/configuration.rb:228:in call’
[493eccc5-67a8-4be5-8924-045d6afe520a] puma (4.3.10) lib/puma/server.rb:718:in handle_request' [493eccc5-67a8-4be5-8924-045d6afe520a] puma (4.3.10) lib/puma/server.rb:472:in process_client’
[493eccc5-67a8-4be5-8924-045d6afe520a] puma (4.3.10) lib/puma/server.rb:328:in block in run' [493eccc5-67a8-4be5-8924-045d6afe520a] puma (4.3.10) lib/puma/thread_pool.rb:134:in block in spawn_thread’
I, [2022-11-24T11:07:54.788835 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:08:54.844879 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:09:54.865811 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:10:54.908960 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:11:54.970853 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:12:55.013738 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:13:39.170869 #40517-173560] INFO – : Started GET “/” for 141.255.166.2 at 2022-11-24 11:13:39 +0100
I, [2022-11-24T11:13:39.204242 #40517-173560] INFO – : Processing by InitController#index as HTML
I, [2022-11-24T11:13:39.218730 #40517-173560] INFO – : Rendering init/index.html.erb within layouts/application
I, [2022-11-24T11:13:39.220698 #40517-173560] INFO – : Rendered init/index.html.erb within layouts/application (Duration: 1.6ms | Allocations: 36)
I, [2022-11-24T11:13:39.227596 #40517-173560] INFO – : Completed 200 OK in 23ms (Views: 6.0ms | ActiveRecord: 5.5ms | Allocations: 2539)
I, [2022-11-24T11:13:55.062818 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:14:55.130518 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:15:55.191342 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:16:55.225235 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:17:55.275683 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:18:55.323612 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:19:55.331744 #40524-5220] INFO – : Scheduler running…
I, [2022-11-24T11:20:38.261467 #40517-173840] INFO – : Started POST “/boaform/admin/formLogin” for 185.246.221.138 at 2022-11-24 11:20:38 +0100
I, [2022-11-24T11:20:38.301678 #40517-173840] INFO – : Processing by ErrorsController#routing as HTML
I, [2022-11-24T11:20:38.301755 #40517-173840] INFO – : Parameters: {“username”=>“admin”, “psd”=>“Feefifofum”, “a”=>“boaform/admin/formLogin”}
E, [2022-11-24T11:20:38.317888 #40517-173840] ERROR – : No route matches [POST] /boaform/admin/formLogin (ActionController::RoutingError)

20221124_1554221.jpg
20221124_1554501.jpg
20221124_1840101.jpg

At the Moment i have no Idea what could be wrong

Yeah no.
Install from the official documentation and not some third party documentation that offers MySQL installations. MySQL is DEPRECATED for Zammad. If you install it now it just doesn’t make any sense.

You can find the official documentation here:
https://docs.zammad.org/en/latest/install/package.html

Hello,
that is the documentation which i used all the times.

The Background is the following
We have a Zammad 5.0.x Installation running on Debian 9 with a Mysql Database and it works fine.
The mission is to upgrade the whole system to the newest versions and i have set up a complete new Server with Debian 11. The final step is to migrate the old zammad Database on the new server.
My Idea was install the same version, backup and restore the database and then upgrade it to the latest version.