E, [2020-09-15T15:08:18.632104 #22198-47059008261420] ERROR -- : Can't use Channel::Driver::Smtp: # E, [2020-09-15T15:08:18.632137 #22198-47059008261420] ERROR -- : 421 4.3.2 Service not available (Net::SMTPServerBusy) /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/net/smtp.rb:969:in `check_response' /opt/zammad/lib/core_ext/net/smtp.rb:15:in `do_start' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/net/smtp.rb:518:in `start' /opt/zammad/vendor/bundle/ruby/2.6.0/bundler/gems/mail-9265cf75bbe3/lib/mail/network/delivery_methods/smtp.rb:109:in `start_smtp_session' /opt/zammad/vendor/bundle/ruby/2.6.0/bundler/gems/mail-9265cf75bbe3/lib/mail/network/delivery_methods/smtp.rb:100:in `deliver!' /opt/zammad/vendor/bundle/ruby/2.6.0/bundler/gems/mail-9265cf75bbe3/lib/mail/message.rb:2159:in `do_delivery' /opt/zammad/vendor/bundle/ruby/2.6.0/bundler/gems/mail-9265cf75bbe3/lib/mail/message.rb:262:in `deliver' /opt/zammad/app/models/channel/driver/smtp.rb:86:in `send' /opt/zammad/app/models/channel.rb:258:in `deliver' /opt/zammad/lib/notification_factory/mailer.rb:154:in `send' /opt/zammad/lib/notification_factory/mailer.rb:207:in `notification' /opt/zammad/app/models/transaction/notification.rb:196:in `block in perform' /opt/zammad/app/models/transaction/notification.rb:91:in `each' /opt/zammad/app/models/transaction/notification.rb:91:in `perform' /opt/zammad/app/models/observer/transaction.rb:62:in `execute_singel_backend' /opt/zammad/app/models/transaction/background_job.rb:30:in `block in perform' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/delegation.rb:71:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/delegation.rb:71:in `each' /opt/zammad/app/models/transaction/background_job.rb:26:in `perform' /opt/zammad/app/models/transaction/background_job.rb:36:in `run' /opt/zammad/app/models/ticket.rb:203:in `block in process_escalation' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `block (2 levels) in find_each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `block in find_each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:136:in `block in find_in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:238:in `block in in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:222:in `loop' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:222:in `in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:135:in `find_in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:69:in `find_each' /opt/zammad/app/models/ticket.rb:193:in `process_escalation' (eval):1:in `_start_job' /opt/zammad/app/models/scheduler.rb:287:in `eval' /opt/zammad/app/models/scheduler.rb:287:in `_start_job' /opt/zammad/app/models/scheduler.rb:239:in `block (2 levels) in start_job' /opt/zammad/app/models/scheduler.rb:237:in `loop' /opt/zammad/app/models/scheduler.rb:237:in `block in start_job' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context' E, [2020-09-15T15:08:18.635834 #22198-47059008261420] ERROR -- : Can't use Channel::Driver::Smtp: # (RuntimeError) /opt/zammad/app/models/channel.rb:269:in `rescue in deliver' /opt/zammad/app/models/channel.rb:255:in `deliver' /opt/zammad/lib/notification_factory/mailer.rb:154:in `send' /opt/zammad/lib/notification_factory/mailer.rb:207:in `notification' /opt/zammad/app/models/transaction/notification.rb:196:in `block in perform' /opt/zammad/app/models/transaction/notification.rb:91:in `each' /opt/zammad/app/models/transaction/notification.rb:91:in `perform' /opt/zammad/app/models/observer/transaction.rb:62:in `execute_singel_backend' /opt/zammad/app/models/transaction/background_job.rb:30:in `block in perform' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/delegation.rb:71:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/delegation.rb:71:in `each' /opt/zammad/app/models/transaction/background_job.rb:26:in `perform' /opt/zammad/app/models/transaction/background_job.rb:36:in `run' /opt/zammad/app/models/ticket.rb:203:in `block in process_escalation' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `block (2 levels) in find_each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:70:in `block in find_each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:136:in `block in find_in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:238:in `block in in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:222:in `loop' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:222:in `in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:135:in `find_in_batches' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/batches.rb:69:in `find_each' /opt/zammad/app/models/ticket.rb:193:in `process_escalation' (eval):1:in `_start_job' /opt/zammad/app/models/scheduler.rb:287:in `eval' /opt/zammad/app/models/scheduler.rb:287:in `_start_job' /opt/zammad/app/models/scheduler.rb:239:in `block (2 levels) in start_job' /opt/zammad/app/models/scheduler.rb:237:in `loop' /opt/zammad/app/models/scheduler.rb:237:in `block in start_job' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context' I, [2020-09-15T15:08:18.955285 #22198-47059008261420] INFO -- : ended Ticket.process_escalation took: 3.303226643 seconds. I, [2020-09-15T15:08:25.683899 #22198-47059007782220] INFO -- : execute Import::OTRS.diff_worker (try_count 0)... I, [2020-09-15T15:08:25.684175 #22198-47059007782220] INFO -- : ended Import::OTRS.diff_worker took: 0.023941384 seconds. I, [2020-09-15T15:08:29.231987 #22172-47405733051840] INFO -- : Started POST "/api/v1/signin" for 172.16.13.27 at 2020-09-15 15:08:29 +0000 I, [2020-09-15T15:08:29.240699 #22172-47405733051840] INFO -- : Processing by SessionsController#create as JSON I, [2020-09-15T15:08:29.240753 #22172-47405733051840] INFO -- : Parameters: {"username"=>"xyz", "password"=>"[FILTERED]", "fingerprint"=>"-1913787697"} E, [2020-09-15T15:08:30.808083 #22172-47405733051840] ERROR -- : PG::UndefinedTable: ERROR: relation "active_job_locks" does not exist LINE 8: WHERE a.attrelid = '"active_job_locks"'::regc... ^ : SELECT a.attname, format_type(a.atttypid, a.atttypmod), pg_get_expr(d.adbin, d.adrelid), a.attnotnull, a.atttypid, a.atttypmod, c.collname, col_description(a.attrelid, a.attnum) AS comment FROM pg_attribute a LEFT JOIN pg_attrdef d ON a.attrelid = d.adrelid AND a.attnum = d.adnum LEFT JOIN pg_type t ON a.atttypid = t.oid LEFT JOIN pg_collation c ON a.attcollation = c.oid AND a.attcollation <> t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum (ActiveRecord::StatementInvalid) /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:63:in `async_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:63:in `block (2 levels) in query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/dependencies/interlock.rb:48:in `block in permit_concurrent_loads' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/concurrency/share_lock.rb:187:in `yield_shares' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/dependencies/interlock.rb:47:in `permit_concurrent_loads' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:62:in `block in query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:581:in `block (2 levels) in log' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:580:in `block in log' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications/instrumenter.rb:23:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:571:in `log' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:61:in `query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql_adapter.rb:757:in `column_definitions' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/schema_statements.rb:113:in `columns' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/schema_cache.rb:69:in `columns' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/schema_cache.rb:75:in `columns_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:466:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/attributes.rb:234:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/attribute_decorators.rb:51:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:459:in `block in load_schema' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:456:in `load_schema' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:346:in `attribute_types' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:370:in `type_for_attribute' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/table_metadata.rb:33:in `type' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:51:in `build' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:112:in `block in expand_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `flat_map' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `expand_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:21:in `build_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/where_clause_factory.rb:19:in `build' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/query_methods.rb:588:in `where!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/query_methods.rb:581:in `where' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/finder_methods.rb:81:in `find_by' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:117:in `active_job_lock' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:107:in `active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:58:in `block in ensure_active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:86:in `in_active_job_lock_transaction' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:57:in `ensure_active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:6:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `block in make_lambda' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:198:in `block (2 levels) in halting' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:606:in `block (2 levels) in default_terminator' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:605:in `catch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:605:in `block in default_terminator' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:199:in `block in halting' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `block in invoke_before' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `invoke_before' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:107:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:17:in `block (3 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:46:in `block in tag_logger' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `block in tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:28:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:46:in `tag_logger' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:16:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:136:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/enqueuing.rb:49:in `enqueue' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/enqueuing.rb:20:in `perform_later' /opt/zammad/app/models/concerns/has_search_index_backend.rb:27:in `search_index_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `block in make_lambda' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:236:in `block in halting_and_conditional' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `block in invoke_after' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `invoke_after' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:133:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:816:in `_run_update_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:350:in `_update_record' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/timestamp.rb:114:in `_update_record' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/persistence.rb:705:in `create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:342:in `block in create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:132:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:816:in `_run_save_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:342:in `create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/persistence.rb:308:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/validations.rb:52:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:315:in `block in save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/database_statements.rb:267:in `block in transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/database_statements.rb:267:in `transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:212:in `transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:385:in `with_transaction_returning_status' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:315:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/suppressor.rb:48:in `save!' /opt/zammad/app/models/user.rb:285:in `authenticate' /opt/zammad/app/controllers/application_controller/authenticates.rb:137:in `authenticate_with_password' /opt/zammad/app/controllers/sessions_controller.rb:10:in `create' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/base.rb:194:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/rendering.rb:30:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/callbacks.rb:42:in `block in process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:109:in `block in run_callbacks' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:18:in `block (4 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:180:in `subscribed' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:17:in `block (3 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:180:in `subscribed' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:16:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:136:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/callbacks.rb:41:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/rescue.rb:22:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:168:in `block in instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications/instrumenter.rb:23:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:168:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/instrumentation.rb:32:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/params_wrapper.rb:256:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/railties/controller_runtime.rb:24:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/base.rb:134:in `process' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionview-5.2.4.4/lib/action_view/rendering.rb:32:in `process' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal.rb:191:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal.rb:252:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:52:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:34:in `serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:52:in `block in serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:35:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:35:in `serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:840:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:420:in `call_app!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-saml-1.10.1/lib/omniauth/strategies/saml.rb:89:in `other_phase' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:190:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/builder.rb:64:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/tempfile_reaper.rb:15:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/conditional_get.rb:40:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/head.rb:12:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/http/content_security_policy.rb:18:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/cookies.rb:670:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:98:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/callbacks.rb:26:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:38:in `call_app' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:26:in `block in call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `block in tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:28:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:26:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/remote_ip.rb:81:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/request_id.rb:27:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/executor.rb:14:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/static.rb:127:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/engine.rb:524:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/configuration.rb:227:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:706:in `handle_request' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:476:in `process_client' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:334:in `block in run' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/thread_pool.rb:135:in `block in spawn_thread' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context' E, [2020-09-15T15:08:30.808724 #22172-47405733051840] ERROR -- : Error ID BbwW2DTA: PG::UndefinedTable: ERROR: relation "active_job_locks" does not exist LINE 8: WHERE a.attrelid = '"active_job_locks"'::regc... ^ : SELECT a.attname, format_type(a.atttypid, a.atttypmod), pg_get_expr(d.adbin, d.adrelid), a.attnotnull, a.atttypid, a.atttypmod, c.collname, col_description(a.attrelid, a.attnum) AS comment FROM pg_attribute a LEFT JOIN pg_attrdef d ON a.attrelid = d.adrelid AND a.attnum = d.adnum LEFT JOIN pg_type t ON a.atttypid = t.oid LEFT JOIN pg_collation c ON a.attcollation = c.oid AND a.attcollation <> t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum I, [2020-09-15T15:08:30.809391 #22172-47405733051840] INFO -- : Completed 422 Unprocessable Entity in 1569ms (Views: 0.4ms | ActiveRecord: 74.6ms) I, [2020-09-15T15:08:35.708807 #22198-47059007735980] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:08:35.710646 #22198-47059007735980] INFO -- : ended Channel.fetch took: 0.04034644 seconds. I, [2020-09-15T15:08:45.702140 #22198-47059008162280] INFO -- : execute Ticket.process_auto_unassign (try_count 0)... I, [2020-09-15T15:08:45.713226 #22198-47059008162280] INFO -- : ended Ticket.process_auto_unassign took: 0.022329577 seconds. I, [2020-09-15T15:08:55.708489 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 0)... E, [2020-09-15T15:08:55.900670 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 0) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.199901007 seconds. I, [2020-09-15T15:08:56.914243 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 1)... E, [2020-09-15T15:08:56.945148 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 1) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.038113713 seconds. I, [2020-09-15T15:08:58.961010 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 2)... E, [2020-09-15T15:08:58.988286 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 2) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.037896114 seconds. I, [2020-09-15T15:09:02.000727 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 3)... E, [2020-09-15T15:09:02.048573 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 3) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.055007636 seconds. I, [2020-09-15T15:09:05.732679 #22198-47059007735980] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:09:05.737777 #22198-47059007735980] INFO -- : ended Channel.fetch took: 0.023540557 seconds. I, [2020-09-15T15:09:05.745351 #22198-47059007713500] INFO -- : execute Stats.generate (try_count 0)... E, [2020-09-15T15:09:05.772389 #22198-47059007713500] ERROR -- : execute Stats.generate (try_count 0) exited with error # in: 0.061147065 seconds. I, [2020-09-15T15:09:06.069663 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 4)... E, [2020-09-15T15:09:06.126379 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 4) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.066914551 seconds. I, [2020-09-15T15:09:06.786484 #22198-47059007713500] INFO -- : execute Stats.generate (try_count 1)... E, [2020-09-15T15:09:06.874282 #22198-47059007713500] ERROR -- : execute Stats.generate (try_count 1) exited with error # in: 0.097897187 seconds. I, [2020-09-15T15:09:08.881857 #22198-47059007713500] INFO -- : execute Stats.generate (try_count 2)... E, [2020-09-15T15:09:08.890724 #22198-47059007713500] ERROR -- : execute Stats.generate (try_count 2) exited with error # in: 0.013493993 seconds. I, [2020-09-15T15:09:11.140497 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 5)... E, [2020-09-15T15:09:11.171653 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 5) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.038956754 seconds. I, [2020-09-15T15:09:11.898673 #22198-47059007713500] INFO -- : execute Stats.generate (try_count 3)... E, [2020-09-15T15:09:11.908497 #22198-47059007713500] ERROR -- : execute Stats.generate (try_count 3) exited with error # in: 0.014912611 seconds. I, [2020-09-15T15:09:15.877438 #22198-47059008828160] INFO -- : execute RecentView.cleanup (try_count 0)... I, [2020-09-15T15:09:15.916968 #22198-47059008828160] INFO -- : ended RecentView.cleanup took: 0.051157717 seconds. I, [2020-09-15T15:09:15.919406 #22198-47059007713500] INFO -- : execute Stats.generate (try_count 4)... E, [2020-09-15T15:09:15.932264 #22198-47059007713500] ERROR -- : execute Stats.generate (try_count 4) exited with error # in: 0.020818819 seconds. I, [2020-09-15T15:09:17.185611 #22198-47059007555100] INFO -- : execute Ticket.process_pending (try_count 6)... E, [2020-09-15T15:09:17.217466 #22198-47059007555100] ERROR -- : execute Ticket.process_pending (try_count 6) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 0.040641757 seconds. I, [2020-09-15T15:09:19.233176 #22198-47058937264540] INFO -- : script/scheduler.rb stopped. I, [2020-09-15T15:13:30.727747 #22980-46961817164180] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:13:32.356432 #22980-46961817164180] INFO -- : Setting.set('models_searchable', ["Ticket", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:13:33.074992 #22980-46961817164180] INFO -- : Migrating to FixedTwitterTicketArticlePreferences7 (20180806000001) I, [2020-09-15T15:13:33.092505 #22980-46961817164180] INFO -- : Migrating to SettingDeliveryTemporaryFailed (20180830000001) I, [2020-09-15T15:13:33.164712 #22980-46961817164180] INFO -- : Migrating to SettingAddSystemBcc (20180913000001) I, [2020-09-15T15:13:33.188633 #22980-46961817164180] INFO -- : Migrating to PermissionAddTimeAccounting (20180913000002) I, [2020-09-15T15:14:35.096138 #23071-47432229607840] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:14:36.483109 #23071-47432229607840] INFO -- : Setting.set('models_searchable', ["Ticket", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:14:36.796440 #23071-47432229607840] INFO -- : Migrating to SettingUpdatePrettyDateFormat (20180919000000) I, [2020-09-15T15:14:36.815899 #23071-47432229607840] INFO -- : Migrating to SettingAddSipgateAlternativeFqdn (20181010000000) I, [2020-09-15T15:14:36.831007 #23071-47432229607840] INFO -- : Migrating to CtiGenericApi2 (20181017000001) I, [2020-09-15T15:14:36.877622 #23071-47432229607840] INFO -- : Migrating to AddStatsBackends (20181023163804) I, [2020-09-15T15:14:36.939683 #23071-47432229607840] INFO -- : Migrating to SettingAddPlacetel1 (20181030000001) I, [2020-09-15T15:14:36.985314 #23071-47432229607840] INFO -- : Migrating to ObjectManagerAttributeIndexes (20181102142526) I, [2020-09-15T15:14:37.015960 #23071-47432229607840] INFO -- : Migrating to AddCountryAttributeToUsers (20181108123847) I, [2020-09-15T15:14:37.046519 #23071-47432229607840] INFO -- : Migrating to Issue2345EsAttachmentMaxSizeInMbSettingLowerDefault (20181120150357) I, [2020-09-15T15:14:37.052047 #23071-47432229607840] INFO -- : Setting.set('es_attachment_max_size_in_mb', 10) I, [2020-09-15T15:14:37.058731 #23071-47432229607840] INFO -- : Migrating to Issue2368AddIndicesToHistoriesAndTickets (20181123000000) I, [2020-09-15T15:14:39.382494 #23071-47432229607840] INFO -- : Migrating to SettingAddEmailFullQuoteHeader (20181203000000) I, [2020-09-15T15:14:39.407666 #23071-47432229607840] INFO -- : Migrating to SettingChangeTicketZoomAttachmentPreview (20190131000001) I, [2020-09-15T15:14:39.433175 #23071-47432229607840] INFO -- : Migrating to SettingTimezoneDefault (20190208000001) I, [2020-09-15T15:14:39.444426 #23071-47432229607840] INFO -- : Migrating to ObjectManagerAttributeDateRemoveFuturePast (20190314084909) I, [2020-09-15T15:14:39.449822 #23071-47432229607840] INFO -- : Migrating to RenameLocaleOnUsers (20190328000000) I, [2020-09-15T15:14:39.455478 #23071-47432229607840] INFO -- : Migrating to DatabaseIndexes (20190405000001) I, [2020-09-15T15:14:40.554324 #23071-47432229607840] INFO -- : Migrating to Issue2541FixNotificationEmailWithoutBody (20190408000001) I, [2020-09-15T15:14:40.592200 #23071-47432229607840] INFO -- : Migrating to SettingTicketOverviewPriorityIconAndColor (20190409000001) I, [2020-09-15T15:14:58.948596 #23126-47320076868000] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:15:00.206802 #23126-47320076868000] INFO -- : Setting.set('models_searchable', ["Ticket", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:15:00.518943 #23126-47320076868000] INFO -- : Migrating to SettingEsMultiIndex (20190415000001) I, [2020-09-15T15:15:00.554484 #23126-47320076868000] INFO -- : Migrating to NormalizeSettingTicketNumberIgnoreSystemId (20190418000000) I, [2020-09-15T15:15:00.568802 #23126-47320076868000] INFO -- : Setting.set('ticket_number_ignore_system_id', false) I, [2020-09-15T15:15:00.572267 #23126-47320076868000] INFO -- : Migrating to InitializeKnowledgeBase (20190531180304) I, [2020-09-15T15:15:16.965769 #23175-47452135135640] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:15:18.445887 #23175-47452135135640] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:15:18.832443 #23175-47452135135640] INFO -- : Migrating to GroupDependentTextModules (20190613000001) I, [2020-09-15T15:15:18.871507 #23175-47452135135640] INFO -- : Migrating to SettingUpdateKarmaLevel (20190626000001) I, [2020-09-15T15:15:18.892741 #23175-47452135135640] INFO -- : Setting.set('karma_levels', [{:name=>"Beginner", :start=>0, :end=>499}, {:name=>"Newbie", :start=>500, :end=>1999}, {:name=>"Intermediate", :start=>2000, :end=>4999}, {:name=>"Professional", :start=>5000, :end=>6999}, {:name=>"Expert", :start=>7000, :end=>8999}, {:name=>"Master", :start=>9000, :end=>18999}, {:name=>"Evangelist", :start=>19000, :end=>49999}, {:name=>"Hero", :start=>50000, :end=>nil}]) I, [2020-09-15T15:15:18.906246 #23175-47452135135640] INFO -- : Migrating to GroupDependentMacros (20190713000001) I, [2020-09-15T15:15:18.954042 #23175-47452135135640] INFO -- : Migrating to SamlAuth (20190715141227) I, [2020-09-15T15:15:18.997147 #23175-47452135135640] INFO -- : Migrating to Issue2641KbColorChangeLimit (20190717210244) I, [2020-09-15T15:15:19.007815 #23175-47452135135640] INFO -- : Migrating to ForgetInsecureSessions (20190718140450) I, [2020-09-15T15:15:19.015701 #23175-47452135135640] INFO -- : Migrating to RenameReservedWords (20190724000001) I, [2020-09-15T15:15:19.091775 #23175-47452135135640] INFO -- : Migrating to SettingPostmasterSendRejectEmail (20190806000001) I, [2020-09-15T15:15:19.102646 #23175-47452135135640] INFO -- : Migrating to ObjectManagerAttributeTicketArticleBodyMaxlengthChange (20190814000001) I, [2020-09-15T15:15:19.128328 #23175-47452135135640] INFO -- : Migrating to Issue2595GitlabPlaceholder (20190903165443) I, [2020-09-15T15:15:19.148980 #23175-47452135135640] INFO -- : Migrating to Issue2867FooterHeaderPublicLink (20190918114553) I, [2020-09-15T15:15:40.662051 #23227-47172930197920] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:15:42.100100 #23227-47172930197920] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:15:42.537950 #23227-47172930197920] INFO -- : Migrating to Issue2867FooterHeaderPublicLink (20190918114553) I, [2020-09-15T15:15:58.260612 #23271-46988690045340] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:15:59.690754 #23271-46988690045340] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:15:59.958673 #23271-46988690045340] INFO -- : Migrating to Issue2867FooterHeaderPublicLink (20190918114553) I, [2020-09-15T15:16:20.812977 #23323-46916885625240] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:16:22.457530 #23323-46916885625240] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:16:22.796971 #23323-46916885625240] INFO -- : Migrating to Issue2867FooterHeaderPublicLink (20190918114553) I, [2020-09-15T15:16:28.744223 #23359-47459280927120] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:16:30.337601 #23359-47459280927120] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:16:30.623691 #23359-47459280927120] INFO -- : Migrating to Issue2867FooterHeaderPublicLink (20190918114553) I, [2020-09-15T15:17:32.330761 #23474-47457446803860] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:17:32.423002 #23456-47211073542560] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:17:32.610653 #23461-46925956184480] INFO -- : Setting.set('product_logo', "5b474d7047a059208a8de7c649b19dce.png") I, [2020-09-15T15:17:37.490090 #23461-46925956184480] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:17:37.622845 #23474-47457446803860] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:17:37.828748 #23456-47211073542560] INFO -- : Setting.set('models_searchable', ["Ticket", "KnowledgeBase::Answer::Translation", "User", "Chat::Session", "Organization"]) I, [2020-09-15T15:17:42.087212 #23474-47457446803860] INFO -- : Scheduler started. I, [2020-09-15T15:17:42.124729 #23474-47457446803860] INFO -- : Cleanup of left over locked delayed jobs 2020-09-15 15:17:42 UTC started. I, [2020-09-15T15:17:42.133678 #23474-47457446803860] INFO -- : Cleanup of left over locked delayed jobs 2020-09-15 15:17:42 UTC finished. I, [2020-09-15T15:17:42.133850 #23474-47457446803860] INFO -- : Cleanup of left over import jobs 2020-09-15 15:17:42 UTC started. I, [2020-09-15T15:17:42.173459 #23474-47457446803860] INFO -- : Cleanup of left over import jobs 2020-09-15 15:17:42 UTC finished. I, [2020-09-15T15:17:42.189641 #23474-47457446803860] INFO -- : Scheduler running... I, [2020-09-15T15:17:42.234081 #23474-47457506303540] INFO -- : execute Ticket.process_escalation (try_count 0)... I, [2020-09-15T15:17:42.939225 #23456-47211137498860] INFO -- : Started GET "/" for 172.16.13.27 at 2020-09-15 15:17:42 +0000 I, [2020-09-15T15:17:42.974307 #23456-47211137498860] INFO -- : Processing by InitController#index as HTML I, [2020-09-15T15:17:42.982345 #23456-47211137498860] INFO -- : Rendering init/index.html.erb within layouts/application I, [2020-09-15T15:17:42.984846 #23456-47211137498860] INFO -- : Rendered init/index.html.erb within layouts/application (1.4ms) I, [2020-09-15T15:17:42.987705 #23456-47211137498860] INFO -- : Completed 200 OK in 12ms (Views: 6.6ms | ActiveRecord: 0.5ms) I, [2020-09-15T15:17:43.196526 #23456-47211148000040] INFO -- : Started POST "/api/v1/signshow" for 172.16.13.27 at 2020-09-15 15:17:43 +0000 I, [2020-09-15T15:17:43.200240 #23456-47211148000040] INFO -- : Processing by SessionsController#show as JSON I, [2020-09-15T15:17:43.200601 #23456-47211148000040] INFO -- : Parameters: {"fingerprint"=>"-1913787697"} I, [2020-09-15T15:17:43.281065 #23474-47457506303540] INFO -- : ended Ticket.process_escalation took: 1.077783853 seconds. I, [2020-09-15T15:17:43.406458 #23456-47211148000040] INFO -- : Completed 200 OK in 206ms (Views: 7.8ms | ActiveRecord: 4.9ms) I, [2020-09-15T15:17:43.438414 #23456-47211148000540] INFO -- : Started GET "/api/v1/translations/lang/de-de?_=1600183063025" for 172.16.13.27 at 2020-09-15 15:17:43 +0000 I, [2020-09-15T15:17:43.442801 #23456-47211148000540] INFO -- : Processing by TranslationsController#lang as JSON I, [2020-09-15T15:17:43.442948 #23456-47211148000540] INFO -- : Parameters: {"_"=>"1600183063025", "locale"=>"de-de"} I, [2020-09-15T15:17:43.463180 #23456-47211148000540] INFO -- : Completed 200 OK in 20ms (Views: 12.8ms | ActiveRecord: 0.4ms) I, [2020-09-15T15:17:46.191453 #23474-47457517573420] INFO -- : Starting worker thread Delayed::Backend::ActiveRecord::Job I, [2020-09-15T15:17:52.234473 #23474-47457519120320] INFO -- : execute Import::OTRS.diff_worker (try_count 0)... I, [2020-09-15T15:17:52.237054 #23474-47457519120320] INFO -- : ended Import::OTRS.diff_worker took: 0.017226508 seconds. I, [2020-09-15T15:17:57.471642 #23456-47211148000900] INFO -- : Started POST "/api/v1/signin" for 172.16.13.27 at 2020-09-15 15:17:57 +0000 I, [2020-09-15T15:17:57.479002 #23456-47211148000900] INFO -- : Processing by SessionsController#create as JSON I, [2020-09-15T15:17:57.479209 #23456-47211148000900] INFO -- : Parameters: {"username"=>"xyz", "password"=>"[FILTERED]", "fingerprint"=>"-1913787697"} E, [2020-09-15T15:17:58.994397 #23456-47211148000900] ERROR -- : PG::UndefinedTable: ERROR: relation "active_job_locks" does not exist LINE 8: WHERE a.attrelid = '"active_job_locks"'::regc... ^ : SELECT a.attname, format_type(a.atttypid, a.atttypmod), pg_get_expr(d.adbin, d.adrelid), a.attnotnull, a.atttypid, a.atttypmod, c.collname, col_description(a.attrelid, a.attnum) AS comment FROM pg_attribute a LEFT JOIN pg_attrdef d ON a.attrelid = d.adrelid AND a.attnum = d.adnum LEFT JOIN pg_type t ON a.atttypid = t.oid LEFT JOIN pg_collation c ON a.attcollation = c.oid AND a.attcollation <> t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum (ActiveRecord::StatementInvalid) /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:63:in `async_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:63:in `block (2 levels) in query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/dependencies/interlock.rb:48:in `block in permit_concurrent_loads' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/concurrency/share_lock.rb:187:in `yield_shares' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/dependencies/interlock.rb:47:in `permit_concurrent_loads' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:62:in `block in query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:581:in `block (2 levels) in log' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:580:in `block in log' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications/instrumenter.rb:23:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract_adapter.rb:571:in `log' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql/database_statements.rb:61:in `query' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/postgresql_adapter.rb:757:in `column_definitions' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/schema_statements.rb:113:in `columns' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/schema_cache.rb:69:in `columns' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/schema_cache.rb:75:in `columns_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:466:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/attributes.rb:234:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/attribute_decorators.rb:51:in `load_schema!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:459:in `block in load_schema' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:456:in `load_schema' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:346:in `attribute_types' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/model_schema.rb:370:in `type_for_attribute' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/table_metadata.rb:33:in `type' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:51:in `build' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:112:in `block in expand_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `flat_map' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:71:in `expand_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/predicate_builder.rb:21:in `build_from_hash' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/where_clause_factory.rb:19:in `build' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/query_methods.rb:588:in `where!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/query_methods.rb:581:in `where' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/relation/finder_methods.rb:81:in `find_by' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:117:in `active_job_lock' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:107:in `active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:58:in `block in ensure_active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:86:in `in_active_job_lock_transaction' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:57:in `ensure_active_job_lock_for_enqueue!' /opt/zammad/app/jobs/concerns/has_active_job_lock.rb:6:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `block in make_lambda' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:198:in `block (2 levels) in halting' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:606:in `block (2 levels) in default_terminator' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:605:in `catch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:605:in `block in default_terminator' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:199:in `block in halting' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `block in invoke_before' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:513:in `invoke_before' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:107:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:17:in `block (3 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:46:in `block in tag_logger' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `block in tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:28:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:46:in `tag_logger' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/logging.rb:16:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:136:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/enqueuing.rb:49:in `enqueue' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activejob-5.2.4.4/lib/active_job/enqueuing.rb:20:in `perform_later' /opt/zammad/app/models/concerns/has_search_index_backend.rb:27:in `search_index_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:426:in `block in make_lambda' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:236:in `block in halting_and_conditional' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `block in invoke_after' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:517:in `invoke_after' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:133:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:816:in `_run_update_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:350:in `_update_record' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/timestamp.rb:114:in `_update_record' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/persistence.rb:705:in `create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:342:in `block in create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:132:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:816:in `_run_save_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/callbacks.rb:342:in `create_or_update' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/persistence.rb:308:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/validations.rb:52:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:315:in `block in save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:387:in `block in with_transaction_returning_status' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/database_statements.rb:267:in `block in transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/transaction.rb:239:in `block in within_new_transaction' /opt/zammad/vendor/ruby-2.6.5/lib/ruby/2.6.0/monitor.rb:235:in `mon_synchronize' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/transaction.rb:236:in `within_new_transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/connection_adapters/abstract/database_statements.rb:267:in `transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:212:in `transaction' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:385:in `with_transaction_returning_status' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/transactions.rb:315:in `save!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/suppressor.rb:48:in `save!' /opt/zammad/app/models/user.rb:285:in `authenticate' /opt/zammad/app/controllers/application_controller/authenticates.rb:137:in `authenticate_with_password' /opt/zammad/app/controllers/sessions_controller.rb:10:in `create' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/basic_implicit_render.rb:6:in `send_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/base.rb:194:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/rendering.rb:30:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/callbacks.rb:42:in `block in process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:109:in `block in run_callbacks' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:18:in `block (4 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:180:in `subscribed' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:17:in `block (3 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:180:in `subscribed' /opt/zammad/app/controllers/application_controller/has_secure_content_security_policy_for_downloads.rb:16:in `block (2 levels) in ' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `instance_exec' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:118:in `block in run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:136:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/callbacks.rb:41:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/rescue.rb:22:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/instrumentation.rb:34:in `block in process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:168:in `block in instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications/instrumenter.rb:23:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/notifications.rb:168:in `instrument' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/instrumentation.rb:32:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal/params_wrapper.rb:256:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activerecord-5.2.4.4/lib/active_record/railties/controller_runtime.rb:24:in `process_action' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/abstract_controller/base.rb:134:in `process' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionview-5.2.4.4/lib/action_view/rendering.rb:32:in `process' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal.rb:191:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_controller/metal.rb:252:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:52:in `dispatch' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:34:in `serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:52:in `block in serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:35:in `each' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/journey/router.rb:35:in `serve' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/routing/route_set.rb:840:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:420:in `call_app!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-saml-1.10.1/lib/omniauth/strategies/saml.rb:89:in `other_phase' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:190:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:192:in `call!' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/strategy.rb:169:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/omniauth-1.9.0/lib/omniauth/builder.rb:64:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/tempfile_reaper.rb:15:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/etag.rb:27:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/conditional_get.rb:40:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/head.rb:12:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/http/content_security_policy.rb:18:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:266:in `context' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/session/abstract/id.rb:260:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/cookies.rb:670:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/callbacks.rb:28:in `block in call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/callbacks.rb:98:in `run_callbacks' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/callbacks.rb:26:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/debug_exceptions.rb:61:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/show_exceptions.rb:33:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:38:in `call_app' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:26:in `block in call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `block in tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:28:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/tagged_logging.rb:71:in `tagged' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/rack/logger.rb:26:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/remote_ip.rb:81:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/request_id.rb:27:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/method_override.rb:24:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/runtime.rb:22:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/activesupport-5.2.4.4/lib/active_support/cache/strategy/local_cache_middleware.rb:29:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/executor.rb:14:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/actionpack-5.2.4.4/lib/action_dispatch/middleware/static.rb:127:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/rack-2.2.3/lib/rack/sendfile.rb:110:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/railties-5.2.4.4/lib/rails/engine.rb:524:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/configuration.rb:227:in `call' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:706:in `handle_request' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:476:in `process_client' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/server.rb:334:in `block in run' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/puma-3.12.6/lib/puma/thread_pool.rb:135:in `block in spawn_thread' /opt/zammad/vendor/bundle/ruby/2.6.0/gems/logging-2.2.2/lib/logging/diagnostic_context.rb:474:in `block in create_with_logging_context' E, [2020-09-15T15:17:58.995204 #23456-47211148000900] ERROR -- : Error ID M589EozD: PG::UndefinedTable: ERROR: relation "active_job_locks" does not exist LINE 8: WHERE a.attrelid = '"active_job_locks"'::regc... ^ : SELECT a.attname, format_type(a.atttypid, a.atttypmod), pg_get_expr(d.adbin, d.adrelid), a.attnotnull, a.atttypid, a.atttypmod, c.collname, col_description(a.attrelid, a.attnum) AS comment FROM pg_attribute a LEFT JOIN pg_attrdef d ON a.attrelid = d.adrelid AND a.attnum = d.adnum LEFT JOIN pg_type t ON a.atttypid = t.oid LEFT JOIN pg_collation c ON a.attcollation = c.oid AND a.attcollation <> t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum I, [2020-09-15T15:17:58.995581 #23456-47211148000900] INFO -- : Completed 422 Unprocessable Entity in 1516ms (Views: 0.2ms | ActiveRecord: 43.1ms) I, [2020-09-15T15:18:02.256914 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:18:02.260752 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.025947846 seconds. I, [2020-09-15T15:18:12.273448 #23474-47457515705720] INFO -- : execute Channel.stream (try_count 0)... I, [2020-09-15T15:18:22.290538 #23474-47457515726140] INFO -- : execute Sessions.jobs (try_count 0)... I, [2020-09-15T15:18:32.270586 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:18:32.274278 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.009924702 seconds. I, [2020-09-15T15:18:32.289013 #23474-47457517918700] INFO -- : execute OnlineNotification.cleanup (try_count 0)... I, [2020-09-15T15:18:42.317392 #23474-47457518635700] INFO -- : execute Job.run (try_count 0)... I, [2020-09-15T15:18:42.320625 #23474-47457518635700] INFO -- : ended Job.run took: 0.03630295 seconds. I, [2020-09-15T15:18:52.428088 #23474-47457518989580] INFO -- : execute Calendar.sync (try_count 0)... I, [2020-09-15T15:18:53.639830 #23474-47457518989580] INFO -- : ended Calendar.sync took: 1.222839643 seconds. I, [2020-09-15T15:19:02.283969 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:19:02.285452 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.008004708 seconds. I, [2020-09-15T15:19:02.423454 #23474-47457520525620] INFO -- : execute Chat.cleanup_close (try_count 0)... I, [2020-09-15T15:19:02.425340 #23474-47457520525620] INFO -- : ended Chat.cleanup_close took: 0.006060777 seconds. I, [2020-09-15T15:19:12.434023 #23474-47457520903040] INFO -- : execute Token.cleanup (try_count 0)... I, [2020-09-15T15:19:12.441681 #23474-47457520903040] INFO -- : ended Token.cleanup took: 0.013924569 seconds. I, [2020-09-15T15:19:22.447316 #23474-47457530879040] INFO -- : execute ActivityStream.cleanup (try_count 0)... I, [2020-09-15T15:19:22.508613 #23474-47457530879040] INFO -- : ended ActivityStream.cleanup took: 0.072668061 seconds. I, [2020-09-15T15:19:32.304509 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:19:32.306007 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.018377317 seconds. I, [2020-09-15T15:19:32.472076 #23474-47457507458660] INFO -- : execute SessionHelper.cleanup_expired (try_count 0)... I, [2020-09-15T15:19:32.512652 #23474-47457507458660] INFO -- : ended SessionHelper.cleanup_expired took: 0.06988246 seconds. I, [2020-09-15T15:19:42.462851 #23474-47457517706100] INFO -- : execute Chat.cleanup (try_count 0)... I, [2020-09-15T15:19:42.466139 #23474-47457517706100] INFO -- : ended Chat.cleanup took: 0.013333119 seconds. I, [2020-09-15T15:19:52.473776 #23474-47457518103060] INFO -- : execute Cti::Log.cleanup (try_count 0)... I, [2020-09-15T15:19:52.475235 #23474-47457518103060] INFO -- : ended Cti::Log.cleanup took: 0.011972666 seconds. I, [2020-09-15T15:20:02.326754 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:20:02.329129 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.018309033 seconds. I, [2020-09-15T15:20:02.483220 #23474-47457518466860] INFO -- : execute StatsStore.cleanup (try_count 0)... I, [2020-09-15T15:20:02.492179 #23474-47457518466860] INFO -- : ended StatsStore.cleanup took: 0.018508655 seconds. I, [2020-09-15T15:20:12.491668 #23474-47457518714020] INFO -- : execute HttpLog.cleanup (try_count 0)... I, [2020-09-15T15:20:16.383780 #23474-47457518714020] INFO -- : ended HttpLog.cleanup took: 3.893498922 seconds. I, [2020-09-15T15:20:18.435748 #23474-47457517918700] INFO -- : ended OnlineNotification.cleanup took: 106.15838513 seconds. I, [2020-09-15T15:20:32.344798 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:20:32.358501 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.025942513 seconds. I, [2020-09-15T15:20:52.251819 #23474-47457519120320] INFO -- : execute Import::OTRS.diff_worker (try_count 0)... I, [2020-09-15T15:20:52.252274 #23474-47457519120320] INFO -- : ended Import::OTRS.diff_worker took: 0.013666186 seconds. I, [2020-09-15T15:21:02.371275 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:21:02.372285 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.008486007 seconds. I, [2020-09-15T15:21:22.551221 #23474-47457446803860] INFO -- : Scheduler running... I, [2020-09-15T15:21:22.583818 #23474-47457446803860] INFO -- : Running job thread for 'Generate Session data' (Sessions.jobs) status is: sleep I, [2020-09-15T15:21:22.584196 #23474-47457446803860] INFO -- : Running job thread for 'Process escalation tickets' (Ticket.process_escalation) status is: sleep I, [2020-09-15T15:21:22.584309 #23474-47457446803860] INFO -- : Running job thread for 'Check Channels' (Channel.fetch) status is: sleep I, [2020-09-15T15:21:22.584390 #23474-47457446803860] INFO -- : Running job thread for 'Check streams for Channel' (Channel.stream) status is: sleep I, [2020-09-15T15:21:22.595159 #23474-47457520159080] INFO -- : execute Ticket.process_auto_unassign (try_count 0)... I, [2020-09-15T15:21:22.627272 #23474-47457520159080] INFO -- : ended Ticket.process_auto_unassign took: 0.04252863 seconds. I, [2020-09-15T15:21:32.383049 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:21:32.384158 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.008343091 seconds. I, [2020-09-15T15:21:32.594936 #23474-47457446803860] INFO -- : Running job thread for 'Import OTRS diff load' (Import::OTRS.diff_worker) status is: sleep I, [2020-09-15T15:21:32.595480 #23474-47457446803860] INFO -- : Running job thread for 'Execute jobs' (Job.run) status is: sleep I, [2020-09-15T15:21:32.608219 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 0)... E, [2020-09-15T15:21:34.975675 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 0) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.378207078 seconds. I, [2020-09-15T15:21:36.005965 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 1)... E, [2020-09-15T15:21:38.198997 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 1) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.211935633 seconds. I, [2020-09-15T15:21:40.212230 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 2)... E, [2020-09-15T15:21:42.936540 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 2) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.729485885 seconds. I, [2020-09-15T15:21:45.948287 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 3)... E, [2020-09-15T15:21:48.435650 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 3) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.493378077 seconds. I, [2020-09-15T15:21:52.448134 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 4)... E, [2020-09-15T15:21:55.134153 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 4) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.692872975 seconds. I, [2020-09-15T15:22:00.151950 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 5)... I, [2020-09-15T15:22:02.393678 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:22:02.394481 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.006637091 seconds. E, [2020-09-15T15:22:02.781612 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 5) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.641438712 seconds. I, [2020-09-15T15:22:08.796702 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 6)... E, [2020-09-15T15:22:11.471429 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 6) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.682091073 seconds. I, [2020-09-15T15:22:18.489639 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 7)... E, [2020-09-15T15:22:20.950681 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 7) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.470169093 seconds. I, [2020-09-15T15:22:28.968147 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 8)... E, [2020-09-15T15:22:31.704949 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 8) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.747420248 seconds. I, [2020-09-15T15:22:32.401578 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:22:32.402570 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.005573709 seconds. I, [2020-09-15T15:22:40.724716 #23474-47457520564840] INFO -- : execute Stats.generate (try_count 9)... I, [2020-09-15T15:22:42.667222 #23474-47457446803860] INFO -- : Scheduler running... I, [2020-09-15T15:22:42.676359 #23474-47457446803860] INFO -- : Running job thread for 'Process escalation tickets' (Ticket.process_escalation) status is: sleep I, [2020-09-15T15:22:42.676581 #23474-47457446803860] INFO -- : Running job thread for 'Check streams for Channel' (Channel.stream) status is: sleep I, [2020-09-15T15:22:42.676790 #23474-47457446803860] INFO -- : Running job thread for 'Generate Session data' (Sessions.jobs) status is: sleep I, [2020-09-15T15:22:42.676931 #23474-47457446803860] INFO -- : Running job thread for 'Check Channels' (Channel.fetch) status is: sleep I, [2020-09-15T15:22:42.677015 #23474-47457446803860] INFO -- : Running job thread for 'Import OTRS diff load' (Import::OTRS.diff_worker) status is: sleep I, [2020-09-15T15:22:42.677402 #23474-47457446803860] INFO -- : Running job thread for 'Generate user based stats.' (Stats.generate) status is: sleep I, [2020-09-15T15:22:42.682074 #23474-47457446803860] INFO -- : Running job thread for 'Execute jobs' (Job.run) status is: sleep E, [2020-09-15T15:22:43.213970 #23474-47457520564840] ERROR -- : execute Stats.generate (try_count 9) exited with error # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > in: 2.502661162 seconds. E, [2020-09-15T15:22:43.222736 #23474-47457520564840] ERROR -- : Failed to run Stats.generate after 10 tries # t.typcollation WHERE a.attrelid = '"active_job_locks"'::regclass AND a.attnum > 0 AND NOT a.attisdropped ORDER BY a.attnum > I, [2020-09-15T15:22:43.292592 #23474-47457506303540] INFO -- : execute Ticket.process_escalation (try_count 0)... I, [2020-09-15T15:22:43.907451 #23474-47457506303540] INFO -- : ended Ticket.process_escalation took: 0.624473068 seconds. I, [2020-09-15T15:23:02.412741 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:23:02.414039 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.008637078 seconds. I, [2020-09-15T15:23:32.423576 #23474-47457510562840] INFO -- : execute Channel.fetch (try_count 0)... I, [2020-09-15T15:23:32.424754 #23474-47457510562840] INFO -- : ended Channel.fetch took: 0.008573148 seconds.