Foreman 17 RC2 - DublicateColums errors

tkoeck · March 22, 2018, 3:00pm

Foreman 17 RC2

does seem to be as buggy as RC1. Empty /var/log/foreman/production.log log

foreman.log

[ WARN 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: rake aborted!
[ WARN 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: Sequel::DatabaseError: PG::DuplicateColumn: ERROR:  column "version" of relation "dynflow_schema_info" already exists
[ WARN 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: /usr/share/foreman/vendor/ruby/2.3.0/gems/sequel-5.6.0/lib/sequel/adapters/postgres.rb:145:in `async_exec'

...
[ WARN 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: PG::DuplicateColumn: ERROR:  column "version" of relation "dynflow_schema_info" already exists
...
[ERROR 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]: Failed to call refresh: '/usr/sbin/foreman-rake db:migrate' returned 1 instead of one of [0]
[ERROR 2018-03-22T15:56:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]: '/usr/sbin/foreman-rake db:migrate' returned 1 instead of one of [0]
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/errors.rb:157:in `fail'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/type/exec.rb:164:in `sync'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/type/exec.rb:604:in `refresh'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:147:in `process_callback'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:34:in `block in process_events'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:119:in `block in queued_events'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:118:in `each'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:118:in `queued_events'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:33:in `process_events'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:278:in `eval_resource'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:181:in `call'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:181:in `block (2 levels) in evaluate'
[ERROR 2018-03-22T15:56:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util.rb:513:in `block in thinmark'

tbrisker · March 22, 2018, 3:33pm

ping @tasks and @Ondrej_Prazak

ekohl · March 22, 2018, 4:16pm

@tkoeck is this on Debian?

tkoeck · March 22, 2018, 4:25pm

Yes. Debian 9 AMD 64 Bit.

I wanted to play around with Foreman 17 for weeks now but every time one Foreman bug/problem is fixed another one pops up. It’s good that I am so patient … but still it’s not very motivating jumping from one bug to the next.

tbrisker · March 22, 2018, 4:45pm

Thank you for testing and reporting issues with the release candidates! It helps us make sure that the final 1.17.0 release will be more stable.

aruzicka · March 26, 2018, 1:48pm

Hello,
could you please give us a bit more to go by? What options did you enable in the installer? Was this a clean install or upgade from 1.16 or previous RC?

tkoeck · March 26, 2018, 1:59pm

It was an upgrade from Foreman 17 RC1. The RC1 didn’t work because of another bug.

Migration from the RC1 database didn’t work. As a second step I deleted / purged the complete PostgreSQL database and let the installer create it again. It had the same duplicate messages.

mmoll · March 26, 2018, 4:06pm

I guess this is https://projects.theforeman.org/issues/20050 or similar.

tkoeck · April 17, 2018, 10:54am

The same problem is with

Foreman

after updating it to 17 and running ‘foreman-installer’.

[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns:
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: Called from
/usr/share/foreman/vendor/ruby/2.3.0/gems/activesupport-5.1.4/lib/active_support/dependencies.rb:258:in `load_dependency'
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: rake aborted
!
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: Sequel::DatabaseError: PG::DuplicateColumn: ERROR:  column "version" of relation "dynflow_schema_info" already exists
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: /usr/share/foreman/vendor/ruby/2.3.0/gems/sequel-5.7.1/lib/sequel/adapters/postgres.rb:146:in `async_exec'
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: /usr/share/foreman/vendor/ruby/2.3.0/gems/sequel-5.7.1/lib/sequel/adapters/postgres.rb:146:in `block in execute_query'
[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-

tkoeck · April 17, 2018, 10:56am

[ WARN 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]/returns: API controll
ers newer than Apipie cache! Run apipie:cache rake task to regenerate cache.
[ERROR 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]: Failed to call refresh: '/usr/sbin/foreman-rake db:migrate' returned 1 instead of one of [0]
[ERROR 2018-04-17T12:52:44 main]  /Stage[main]/Foreman::Database/Foreman::Rake[db:migrate]/Exec[foreman-rake-db:migrate]: '/usr/sbin/foreman-rake db:migrate' returned 1 instead of one of [0]
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util/errors.rb:157:in `fail'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/type/exec.rb:164:in `sync'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/type/exec.rb:604:in `refresh'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:147:in `process_callback'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:34:in `block in process_events'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:119:in `block in queued_events'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:118:in `each'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:118:in `queued_events'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction/event_manager.rb:33:in `process_events'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:278:in `eval_resource'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:181:in `call'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:181:in `block (2 levels) in evaluate'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util.rb:513:in `block in thinmark'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/2.4.0/benchmark.rb:308:in `realtime'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/util.rb:512:in `thinmark'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:181:in `block in evaluate'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/graph/relationship_graph.rb:121:in `traverse'
[ERROR 2018-04-17T12:52:44 main] /opt/puppetlabs/puppet/lib/ruby/vendor_ruby/puppet/transaction.rb:171:in `evaluate'
[ERROR 2018-04-17T12:52:44 main] /usr/lib/ruby/vendor_ruby/kafo/modules/kafo_configure/lib/puppet/parser/functions/add_progress.rb:30:in `evaluate_with_trigger'