Skip to content

eMail send 4 times - sidekiq issue?

Dear, after updating to 15.10.2 from 15.9.3 we have an issue with sending emails multiple times (4 times) every time.

In the sidekiq error log we see the following (below the complete log line): "error_message":"Net::ReadTimeout with #\\u003cSocket:(closed)\\u003e","error_class":"Net::ReadTimeout",

All services are running fine and restarted all services as well if we do a: gitlab-rake gitlab:check We don't see any errors

Checking GitLab subtasks ...

Checking GitLab Shell ...

GitLab Shell: ... GitLab Shell version >= 14.18.0 ? ... OK (14.18.0) Running /opt/gitlab/embedded/service/gitlab-shell/bin/check Internal API available: OK Redis available via internal API: OK gitlab-shell self-check successful

Checking GitLab Shell ... Finished

Checking Gitaly ...

Gitaly: ... default ... OK

Checking Gitaly ... Finished

Checking Sidekiq ...

Sidekiq: ... Running? ... yes Number of Sidekiq processes (cluster/worker) ... 1/1

Checking Sidekiq ... Finished

Complete log line:

{"severity":"WARN","time":"2023-04-12T10:08:02.581Z","retry":3,"queue":"mailers","class":"ActionMailer::MailDeliveryJob","args":["[FILTERED]"],"jid":"3da60b8905998cfdb7c925b8","created_at":"2023-04-12T10:06:12.145Z","correlation_id":"7e9fc1f3c9def975e4c8189467ddc7f9","meta.caller_id":"NewNoteWorker","meta.remote_ip":"XXX","meta.feature_category":"team_planning","meta.user":"XXX","meta.user_id":18,"meta.project":"XXX","meta.root_namespace":"XXX","meta.client_id":"XXX","meta.root_caller_id":"Projects::NotesController#create","worker_data_consistency":"always","idempotency_key":"resque:gitlab:duplicate:mailers:e0ccbdb80f8ada5c8201633e046810f3f4b33f2b3bda1efc2a5a9febacdc6d51","size_limiter":"validated","enqueued_at":"2023-04-12T10:07:56.631Z","error_message":"Net::ReadTimeout with #\u003cSocket:(closed)\u003e","error_class":"Net::ReadTimeout","failed_at":"2023-04-12T10:06:24.429Z","retry_count":2,"retried_at":"2023-04-12T10:07:21.189Z","job_size_bytes":385,"pid":12224,"message":"ActionMailer::MailDeliveryJob JID-3da60b8905998cfdb7c925b8: fail: 5.946594 sec","job_status":"fail","scheduling_latency_s":0.001906,"redis_calls":2,"redis_duration_s":0.000655,"redis_read_bytes":1068,"redis_write_bytes":168,"redis_cache_calls":1,"redis_cache_duration_s":0.000411,"redis_cache_read_bytes":1067,"redis_cache_write_bytes":45,"redis_queues_calls":1,"redis_queues_duration_s":0.000244,"redis_queues_read_bytes":1,"redis_queues_write_bytes":123,"db_count":40,"db_write_count":3,"db_cached_count":9,"db_replica_count":0,"db_primary_count":40,"db_main_count":40,"db_main_replica_count":0,"db_replica_cached_count":0,"db_primary_cached_count":9,"db_main_cached_count":9,"db_main_replica_cached_count":0,"db_replica_wal_count":0,"db_primary_wal_count":0,"db_main_wal_count":0,"db_main_replica_wal_count":0,"db_replica_wal_cached_count":0,"db_primary_wal_cached_count":0,"db_main_wal_cached_count":0,"db_main_replica_wal_cached_count":0,"db_replica_duration_s":0.0,"db_primary_duration_s":0.032,"db_main_duration_s":0.032,"db_main_replica_duration_s":0.0,"cpu_s":0.242682,"mem_objects":110684,"mem_bytes":15298520,"mem_mallocs":127075,"mem_total_bytes":19725880,"worker_id":"sidekiq_0","rate_limiting_gates":[],"duration_s":5.946594,"completed_at":"2023-04-12T10:08:02.579Z","load_balancing_strategy":"primary","exception.class":"Net::ReadTimeout","exception.message":"Net::ReadTimeout with #\u003cSocket:(closed)\u003e","exception.backtrace":["lib/gitlab/database/load_balancing/sidekiq_server_middleware.rb:26:in call'","lib/gitlab/sidekiq_middleware/duplicate_jobs/strategies/until_executing.rb:16:in perform'","lib/gitlab/sidekiq_middleware/duplicate_jobs/duplicate_job.rb:44:in perform'","lib/gitlab/sidekiq_middleware/duplicate_jobs/server.rb:8:in call'","lib/gitlab/sidekiq_middleware/worker_context.rb:9:in wrap_in_optional_context'","lib/gitlab/sidekiq_middleware/worker_context/server.rb:19:in block in call'","lib/gitlab/application_context.rb:115:in block in use'","lib/gitlab/application_context.rb:115:in use'","lib/gitlab/application_context.rb:55:in with_context'","lib/gitlab/sidekiq_middleware/worker_context/server.rb:17:in call'","lib/gitlab/sidekiq_status/server_middleware.rb:7:in call'","lib/gitlab/sidekiq_versioning/middleware.rb:9:in call'","lib/gitlab/sidekiq_middleware/query_analyzer.rb:7:in block in call'","lib/gitlab/database/query_analyzer.rb:37:in within'","lib/gitlab/sidekiq_middleware/query_analyzer.rb:7:in call'","lib/gitlab/sidekiq_middleware/admin_mode/server.rb:14:in call'","lib/gitlab/sidekiq_middleware/instrumentation_logger.rb:9:in call'","lib/gitlab/sidekiq_middleware/batch_loader.rb:7:in call'","lib/gitlab/sidekiq_middleware/extra_done_log_metadata.rb:7:in call'","lib/gitlab/sidekiq_middleware/request_store_middleware.rb:10:in block in call'","lib/gitlab/with_request_store.rb:17:in enabling_request_store'","lib/gitlab/with_request_store.rb:10:in with_request_store'","lib/gitlab/sidekiq_middleware/request_store_middleware.rb:9:in call'","lib/gitlab/sidekiq_middleware/server_metrics.rb:76:in block in call'","lib/gitlab/sidekiq_middleware/server_metrics.rb:103:in block in instrument'","lib/gitlab/metrics/background_transaction.rb:33:in run'","lib/gitlab/sidekiq_middleware/server_metrics.rb:103:in instrument'","lib/gitlab/sidekiq_middleware/server_metrics.rb:75:in call'","lib/gitlab/sidekiq_middleware/monitor.rb:10:in block in call'","lib/gitlab/sidekiq_daemon/monitor.rb:46:in within_job'","lib/gitlab/sidekiq_middleware/monitor.rb:9:in call'","lib/gitlab/sidekiq_middleware/size_limiter/server.rb:13:in call'","lib/gitlab/sidekiq_logging/structured_logger.rb:21:in call'"],"db_duration_s":0.029248}`