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)
At the Moment i have no Idea what could be wrong