Content
Issues after update from V12.4.5 to V12.5.0
Added by Gerald EN over 1 year ago
Dear Community,
after an version update from 12.4.5->12.5.0 this morning our OP server is not functional anymore.
The server reboots permanently and the logfile shows the following entries:
################LOG################
Mar 20 20:52:28 svrmgmt systemd[1]: openproject-web-1.service: Main process exited, code=exited, status=1/FAILURE
Mar 20 20:52:28 svrmgmt systemd[1]: openproject-web-1.service: Failed with result 'exit-code'.
Mar 20 20:52:29 svrmgmt systemd[1]: openproject-web-1.service: Service RestartSec=100ms expired, scheduling restart.
Mar 20 20:52:29 svrmgmt systemd[1]: openproject-web-1.service: Scheduled restart job, restart counter is at 549.
Mar 20 20:52:29 svrmgmt systemd[1]: Stopped openproject-web-1.service.
Mar 20 20:52:29 svrmgmt systemd[1]: Started openproject-web-1.service.
Mar 20 20:52:40 svrmgmt openproject-web-1.service[92495]: /opt/openproject/config/constants/settings/definition.rb:1279:in `find_env_var_override': Using unprefixed environment variables is deprecated. Please use OPENPROJECT_RAILS_CACHE_STORE instead of RAILS_CACHE_STORE (StructuredWarnings::StandardWarning)
Mar 20 20:52:40 svrmgmt openproject-web-1.service[92495]: => Booting Puma
Mar 20 20:52:40 svrmgmt openproject-web-1.service[92495]: => Rails 7.0.4.2 application starting in production
Mar 20 20:52:40 svrmgmt openproject-web-1.service[92495]: => Run `bin/rails server --help` for more startup options
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: Exiting
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: /opt/openproject/vendor/bundle/ruby/3.2.0/gems/activerecord-7.0.4.2/lib/active_record/migration.rb:627:in `check_pending!': (ActiveRecord::PendingMigrationError)
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: Migrations are pending. To resolve this issue, run:
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: bin/rails db:migrate RAILS_ENV=production
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: You have 10 pending migrations:
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221029194419_add_timestamps_to_queries.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221129074635_remove_activity_type_from_journals_table.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221130150352_create_project_journals.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221201140825_add_non_null_constraint_on_projects_identifier.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221202130039_fill_project_journals_with_existing_data.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20221213092910_remove_default_values_from_journal_tables_definition.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20230130134630_add_index_for_work_package_journals_project_id.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20230306083203_non_null_data_reference_on_journals.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20230309104056_unique_index_on_custom_fields_projects.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: 20230314093106_add_update_delete_cascade_to_work_packages_project_id.rb
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/config/initializers/06-pending_migrations_check.rb:34:in `<top (required)>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:667:in `load'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:667:in `block in load_config_initializer'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/activesupport-7.0.4.2/lib/active_support/notifications.rb:208:in `instrument'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:666:in `load_config_initializer'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:620:in `block (2 levels) in <class:Engine>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:619:in `each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/engine.rb:619:in `block in <class:Engine>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:32:in `instance_exec'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:32:in `run'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:61:in `block in run_initializers'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:228:in `block in tsort_each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:350:in `block (2 levels) in each_strongly_connected_component'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:422:in `block (2 levels) in each_strongly_connected_component_from'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:431:in `each_strongly_connected_component_from'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:421:in `block in each_strongly_connected_component_from'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:50:in `each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:50:in `tsort_each_child'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:415:in `call'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:415:in `each_strongly_connected_component_from'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:349:in `block in each_strongly_connected_component'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:347:in `each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:347:in `call'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:347:in `each_strongly_connected_component'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:226:in `tsort_each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/tsort.rb:205:in `tsort_each'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/initializable.rb:60:in `run_initializers'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/application.rb:372:in `initialize!'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/railtie.rb:226:in `public_send'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/railtie.rb:226:in `method_missing'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/config/environment.rb:32:in `<top (required)>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from <internal:/opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/rubygems/core_ext/kernel_require.rb>:37:in `require'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/zeitwerk-2.6.7/lib/zeitwerk/kernel.rb:38:in `require'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from config.ru:31:in `block in <main>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/builder.rb:116:in `eval'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/builder.rb:116:in `new_from_string'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/builder.rb:105:in `load_file'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/builder.rb:66:in `parse_file'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:349:in `build_app_and_options_from_config'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:249:in `app'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:422:in `wrapped_app'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:312:in `block in start'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:379:in `handle_profiling'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/rack-2.2.6.3/lib/rack/server.rb:311:in `start'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/commands/server/server_command.rb:38:in `start'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/commands/server/server_command.rb:143:in `block in perform'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from <internal:kernel>:90:in `tap'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/commands/server/server_command.rb:134:in `perform'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/thor-1.2.1/lib/thor/command.rb:27:in `run'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/thor-1.2.1/lib/thor/invocation.rb:127:in `invoke_command'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/thor-1.2.1/lib/thor.rb:392:in `dispatch'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/command/base.rb:87:in `perform'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/command.rb:48:in `invoke'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from /opt/openproject/vendor/bundle/ruby/3.2.0/gems/railties-7.0.4.2/lib/rails/commands.rb:18:in `<top (required)>'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from <internal:/opt/openproject/vendor/ruby-3.2.1/lib/ruby/3.2.0/rubygems/core_ext/kernel_require.rb>:37:in `require'
Mar 20 20:52:41 svrmgmt openproject-web-1.service[92495]: from bin/rails:9:in `<main>'
Mar 20 20:52:41 svrmgmt systemd[1]: openproject-web-1.service: Main process exited, code=exited, status=1/FAILURE
Mar 20 20:52:41 svrmgmt systemd[1]: openproject-web-1.service: Failed with result 'exit-code'.
Mar 20 20:52:41 svrmgmt systemd[1]: openproject-web-1.service: Service RestartSec=100ms expired, scheduling restart.
Mar 20 20:52:41 svrmgmt systemd[1]: openproject-web-1.service: Scheduled restart job, restart counter is at 550.
Mar 20 20:52:41 svrmgmt systemd[1]: Stopped openproject-web-1.service.
Mar 20 20:52:41 svrmgmt systemd[1]: Started openproject-web-1.service.
################LOG#################
/opt/openproject/bin/rails db:migrate RAILS_ENV=production
Traceback (most recent call last):
4: from /opt/openproject/bin/rails:3:in `<main>'
3: from /opt/openproject/bin/rails:3:in `load'
2: from /opt/openproject/bin/spring:8:in `<top (required)>'
1: from /usr/share/rubygems/rubygems/core_ext/kernel_require.rb:59:in `require'
/usr/share/rubygems/rubygems/core_ext/kernel_require.rb:59:in `require': cannot load such file -- bundler (LoadError)
____________________________________________________
OS: Rocky Linux release 8.7
Any idea how I can solve this problem?
Thanks
Gerald
Replies (5)
Same here, enterprise edition in Docker.
@Gerald
It says there are 10 migrations missing. Did you perhaps simply not run
sudo openproject configure
after the update?Can you please try that and send us any output if it should fail?
@Rene
This seems to be a separate issue. If Gerald had the same issue there would be only 2 migrations missing, not 10, as the migration that fails for you is the second to last one (https://github.com/opf/openproject/blob/release/12.5/db/migrate/20230309104056_unique_index_on_custom_fields_projects.rb).
I'll answer that in the ticket you created.
@Markus
After downgrading to 12.4.5 and running sudo openproject configure -> OP was functional again.
Upgrading to 12.5.1 and running sudo openproject configure -> OP stays functional.
You are right in 12.5.0 i forgot about the cli command after the upgrade.
Sorry for the confusion.
No worries. I'm glad it's working for you now!
Markus Kahl wrote:
Nice source!