rspec background_migration pg14 2/17
Passed Started
by
@stomlinson
Simon Tomlinson
222:11:59 on blue-3.shared-gitlab-org.runners-manager.gitlab.com/default sUrYYgEG, system ID: s_35f98e2af465622:11:59Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...722:12:02Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...922:12:03Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...1022:12:04Using docker image sha256:82f132c22567f8ce17cc132c14b2f9aa3eaf9bb5424d91c2d966912433f0257d for registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:c55e1364ee4327b244a9cfa1750f19feea14fc774eaf792b725967d146eb45f5 ...1122:12:05WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.1222:12:05WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.1322:12:05Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...1522:12:05Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...1622:12:06Using docker image sha256:5c5ba5b83d6c60cb0b225bd670b7a43be8c238cc72347d24af57e5027c3648f3 for registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 with digest registry.gitlab.com/gitlab-org/gitlab-build-images@sha256:46aceab84c42d0e819c8e7940f2f98ce9e184785cd7b22d95872024a35f52407 ...1922:12:08Using docker image sha256:4100b5bd17430263e85ad9d8ab851959b234acaaf378e093f1f98400f4998815 for redis:6.2-alpine with digest redis@sha256:eaba718fecd1196d88533de7ba49bf903ad33664a92debb24660a922ecd9cac8 ...2222:12:16Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...2322:12:17Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...2522:12:17Running on runner-suryygeg-project-13083-concurrent-0 via runner-suryygeg-shared-gitlab-org-1731530802-0bde55e2...3622:12:44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300418 responseStatus=200 OK token=glcbt-664022:12:58Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300423 responseStatus=200 OK token=glcbt-664222:13:05Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300433 responseStatus=200 OK token=glcbt-664422:13:05Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300434 responseStatus=200 OK token=glcbt-664622:13:10Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8353300427 responseStatus=200 OK token=glcbt-664822:13:22Using docker image sha256:e83f017c5ffd177beb4bc3b4bfabde989d741e04fef90cd764896cf9b670552f for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14:rubygems-3.5-git-2.45-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 with digest registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.2.5-golang-1.23-rust-1.73-node-20.12-postgresql-14@sha256:770feb0a974e36f8b83d22a3cb18310d0cfeae11e02f6195b1577c73ce26b398 ...5022:13:26$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb17622:15:50$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"18522:15:58$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"19122:15:59KNAPSACK_TEST_FILE_PATTERN: spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb19522:15:59FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_background_migration_pg14_2_17_13083_report.json19622:15:59NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_background_migration_pg14_2_17_13083_report.json20722:15:59/builds/gitlab-org/gitlab-foss/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!20822:16:36DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <top (required)> at /builds/gitlab-org/gitlab-foss/config/environment.rb:7)20922:16:43warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.21122:16:49/builds/gitlab-org/gitlab-foss/vendor/ruby/3.2.0/gems/io-event-1.6.5/lib/io/event/support.rb:27: warning: IO::Buffer is experimental and both the Ruby and C interface may change in the future!21222:16:58DEPRECATION WARNING: Support for Rails versions < 7.1 is deprecated and will be removed from ViewComponent 4.0.0 (ViewComponent v4 will remove support for Rails versions < 7.1 no earlier than April 1, 2025) (called from <main> at /builds/gitlab-org/gitlab-foss/config/environment.rb:7)21622:17:24unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.21722:17:24unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.21922:17:32# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/job_coordinator_spec.rb. Expected to take 3 minutes 3.85 seconds.22722:17:49# [RSpecRunTime] RSpec elapsed time: 24.59 seconds. Current RSS: ~916M. Threads: 1. load average: 1.12 5.78 5.62 1/260 41023122:17:55# [RSpecRunTime] RSpec elapsed time: 30.3 seconds. Current RSS: ~916M. Threads: 1. load average: 1.19 5.71 5.60 1/260 41123822:18:05# [RSpecRunTime] RSpec elapsed time: 40.71 seconds. Current RSS: ~912M. Threads: 1. load average: 1.16 5.56 5.55 1/260 41224222:18:10# [RSpecRunTime] RSpec elapsed time: 45.9 seconds. Current RSS: ~913M. Threads: 1. load average: 1.15 5.48 5.52 1/260 41324622:18:16# [RSpecRunTime] RSpec elapsed time: 51.18 seconds. Current RSS: ~913M. Threads: 1. load average: 1.13 5.41 5.50 1/260 41425222:18:21# [RSpecRunTime] RSpec elapsed time: 56.67 seconds. Current RSS: ~917M. Threads: 1. load average: 1.12 5.33 5.47 1/260 41526222:18:52# [RSpecRunTime] RSpec elapsed time: 1 minute 27.46 seconds. Current RSS: ~915M. Threads: 1. load average: 0.93 4.89 5.32 1/261 41626622:18:57# [RSpecRunTime] RSpec elapsed time: 1 minute 32.73 seconds. Current RSS: ~915M. Threads: 1. load average: 1.02 4.84 5.30 1/261 41727022:19:02# [RSpecRunTime] RSpec elapsed time: 1 minute 37.99 seconds. Current RSS: ~919M. Threads: 1. load average: 1.02 4.77 5.28 1/261 41827422:19:08# [RSpecRunTime] RSpec elapsed time: 1 minute 43.39 seconds. Current RSS: ~919M. Threads: 1. load average: 1.01 4.71 5.26 1/261 41927822:19:13# [RSpecRunTime] RSpec elapsed time: 1 minute 48.78 seconds. Current RSS: ~920M. Threads: 1. load average: 1.08 4.60 5.21 1/261 42028322:19:19# [RSpecRunTime] RSpec elapsed time: 1 minute 54.2 seconds. Current RSS: ~919M. Threads: 1. load average: 1.00 4.53 5.19 1/261 42128722:19:24# [RSpecRunTime] RSpec elapsed time: 1 minute 59.53 seconds. Current RSS: ~921M. Threads: 1. load average: 1.00 4.47 5.16 1/261 42229322:19:34# [RSpecRunTime] RSpec elapsed time: 2 minutes 9.89 seconds. Current RSS: ~923M. Threads: 1. load average: 1.00 4.35 5.12 1/261 42329722:19:40# [RSpecRunTime] RSpec elapsed time: 2 minutes 15.19 seconds. Current RSS: ~923M. Threads: 1. load average: 1.00 4.30 5.10 1/261 42430422:19:55# [RSpecRunTime] RSpec elapsed time: 2 minutes 30.57 seconds. Current RSS: ~922M. Threads: 1. load average: 0.93 4.12 5.02 1/261 42530922:20:05# [RSpecRunTime] RSpec elapsed time: 2 minutes 40.88 seconds. Current RSS: ~924M. Threads: 1. load average: 0.86 4.00 4.97 1/262 42731522:20:15# [RSpecRunTime] RSpec elapsed time: 2 minutes 51.13 seconds. Current RSS: ~922M. Threads: 1. load average: 0.88 3.90 4.93 1/262 42832222:20:32# [RSpecRunTime] RSpec elapsed time: 3 minutes 7.59 seconds. Current RSS: ~917M. Threads: 1. load average: 0.83 3.74 4.86 1/262 42932422:20:44# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/job_coordinator_spec.rb. It took 3 minutes 12.26 seconds. Expected to take 3 minutes 3.85 seconds.32522:20:44# [RSpecRunTime] RSpec elapsed time: 3 minutes 19.69 seconds. Current RSS: ~913M. Threads: 1. load average: 0.87 3.61 4.80 1/262 43032722:20:44# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb. Expected to take 1 minute 46.16 seconds.33222:22:18# [RSpecRunTime] RSpec elapsed time: 4 minutes 53.76 seconds. Current RSS: ~940M. Threads: 1. load average: 1.01 2.92 4.44 1/261 43133622:22:31# [RSpecRunTime] RSpec elapsed time: 5 minutes 6.32 seconds. Current RSS: ~931M. Threads: 1. load average: 1.01 2.85 4.40 1/261 43234022:22:32INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows34122:22:32I, [2024-11-13T22:22:32.619901 #344] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes34222:22:32I, [2024-11-13T22:22:32.621324 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}34322:22:32I, [2024-11-13T22:22:32.622265 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}34422:22:34I, [2024-11-13T22:22:34.314478 #344] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes34522:22:42I, [2024-11-13T22:22:42.990055 #344] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes34622:23:00I, [2024-11-13T22:23:00.281830 #344] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes34722:23:00I, [2024-11-13T22:23:00.283453 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}34822:23:00I, [2024-11-13T22:23:00.284430 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}34922:23:15I, [2024-11-13T22:23:15.476756 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes35022:23:24I, [2024-11-13T22:23:24.571239 #344] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes35122:23:34I, [2024-11-13T22:23:34.366734 #344] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes35222:23:37I, [2024-11-13T22:23:37.510657 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes35522:23:42INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows35622:23:42I, [2024-11-13T22:23:42.009888 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes35722:23:42I, [2024-11-13T22:23:42.011256 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}35822:23:42I, [2024-11-13T22:23:42.012222 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}35922:23:49I, [2024-11-13T22:23:49.327970 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes36022:24:02I, [2024-11-13T22:24:02.937940 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes36122:24:02I, [2024-11-13T22:24:02.939356 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}36222:24:02I, [2024-11-13T22:24:02.940338 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}36322:24:02I, [2024-11-13T22:24:02.944497 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes36422:24:02I, [2024-11-13T22:24:02.945942 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}36522:24:02I, [2024-11-13T22:24:02.947223 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}36622:24:02I, [2024-11-13T22:24:02.951351 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes36722:24:02I, [2024-11-13T22:24:02.952667 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}36822:24:02I, [2024-11-13T22:24:02.953668 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}36922:24:02I, [2024-11-13T22:24:02.957775 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes37022:24:02I, [2024-11-13T22:24:02.959029 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}37122:24:02I, [2024-11-13T22:24:02.959964 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}37222:24:04I, [2024-11-13T22:24:04.154855 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes37322:24:04I, [2024-11-13T22:24:04.156212 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}37422:24:04I, [2024-11-13T22:24:04.157149 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}37522:24:04I, [2024-11-13T22:24:04.160971 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes37622:24:04I, [2024-11-13T22:24:04.162239 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}37722:24:04I, [2024-11-13T22:24:04.163159 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}37822:24:04I, [2024-11-13T22:24:04.167143 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes37922:24:04I, [2024-11-13T22:24:04.168385 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}38022:24:04I, [2024-11-13T22:24:04.169252 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}38122:24:04I, [2024-11-13T22:24:04.173337 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes38222:24:04I, [2024-11-13T22:24:04.174699 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}38322:24:04I, [2024-11-13T22:24:04.175648 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}38422:24:25# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb. It took 3 minutes 41.17 seconds. Expected to take 1 minute 46.16 seconds.38522:24:25# [RSpecRunTime] RSpec elapsed time: 7 minutes 0.89 second. Current RSS: ~930M. Threads: 1. load average: 0.92 2.22 3.98 1/261 43338722:24:25# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ci_job_variables_project_id_spec.rb. Expected to take 1 minute 29.26 seconds.39122:26:00I, [2024-11-13T22:26:00.279177 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes39422:26:05INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows39522:26:05I, [2024-11-13T22:26:05.606719 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes39622:26:05I, [2024-11-13T22:26:05.607982 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}39722:26:05I, [2024-11-13T22:26:05.608880 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}39822:26:12I, [2024-11-13T22:26:12.828441 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes39922:26:25I, [2024-11-13T22:26:25.320061 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes40022:26:25I, [2024-11-13T22:26:25.321543 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}40122:26:25I, [2024-11-13T22:26:25.323323 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}40222:26:25I, [2024-11-13T22:26:25.328531 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes40322:26:25I, [2024-11-13T22:26:25.329906 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}40422:26:25I, [2024-11-13T22:26:25.330893 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}40522:26:25I, [2024-11-13T22:26:25.334959 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes40622:26:25I, [2024-11-13T22:26:25.336342 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}40722:26:25I, [2024-11-13T22:26:25.337333 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}40822:26:25I, [2024-11-13T22:26:25.341834 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes40922:26:25I, [2024-11-13T22:26:25.343099 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}41022:26:25I, [2024-11-13T22:26:25.344020 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}41122:26:26I, [2024-11-13T22:26:26.517027 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes41222:26:26I, [2024-11-13T22:26:26.518383 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}41322:26:26I, [2024-11-13T22:26:26.519291 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}41422:26:26I, [2024-11-13T22:26:26.523464 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes41522:26:26I, [2024-11-13T22:26:26.524715 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}41622:26:26I, [2024-11-13T22:26:26.525681 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}41722:26:26I, [2024-11-13T22:26:26.529729 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes41822:26:26I, [2024-11-13T22:26:26.530955 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}41922:26:26I, [2024-11-13T22:26:26.531927 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}42022:26:26I, [2024-11-13T22:26:26.536216 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes42122:26:26I, [2024-11-13T22:26:26.537654 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}42222:26:26I, [2024-11-13T22:26:26.538689 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}42322:26:41I, [2024-11-13T22:26:41.156084 #344] INFO -- : Database: 'ci', Table: 'security_policy_requirements': Lock Writes42422:26:41I, [2024-11-13T22:26:41.585345 #344] INFO -- : Database: 'ci', Table: 'workspaces_agent_configs': Lock Writes42522:26:41I, [2024-11-13T22:26:41.830795 #344] INFO -- : Database: 'ci', Table: 'workspaces_agent_config_versions': Lock Writes42622:26:42I, [2024-11-13T22:26:42.047832 #344] INFO -- : Database: 'ci', Table: 'project_security_exclusions': Lock Writes42722:26:42I, [2024-11-13T22:26:42.089602 #344] INFO -- : Database: 'ci', Table: 'group_security_exclusions': Lock Writes42822:26:42I, [2024-11-13T22:26:42.494181 #344] INFO -- : Database: 'ci', Table: 'instance_integrations': Lock Writes43122:26:49INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows43222:26:58I, [2024-11-13T22:26:58.075837 #344] INFO -- : Database: 'ci', Table: 'subscription_seat_assignments': Lock Writes43322:26:58I, [2024-11-13T22:26:58.118471 #344] INFO -- : Database: 'ci', Table: 'vulnerability_management_policy_rules': Lock Writes43422:26:58I, [2024-11-13T22:26:58.160001 #344] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_applications': Lock Writes43522:26:58I, [2024-11-13T22:26:58.197202 #344] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_graph_access_tokens': Lock Writes43622:26:58I, [2024-11-13T22:26:58.266933 #344] INFO -- : Database: 'ci', Table: 'packages_conan_recipe_revisions': Lock Writes43722:26:58I, [2024-11-13T22:26:58.461835 #344] INFO -- : Database: 'ci', Table: 'packages_conan_package_references': Lock Writes43822:26:58I, [2024-11-13T22:26:58.794161 #344] INFO -- : Database: 'ci', Table: 'merge_request_merge_schedules': Lock Writes43922:26:59I, [2024-11-13T22:26:59.358269 #344] INFO -- : Database: 'ci', Table: 'duo_workflows_events': Lock Writes44022:26:59I, [2024-11-13T22:26:59.403902 #344] INFO -- : Database: 'ci', Table: 'wiki_page_meta_user_mentions': Lock Writes44122:26:59I, [2024-11-13T22:26:59.704862 #344] INFO -- : Database: 'ci', Table: 'packages_conan_package_revisions': Lock Writes44222:27:00I, [2024-11-13T22:27:00.966459 #344] INFO -- : Database: 'ci', Table: 'project_security_statistics': Lock Writes44322:27:01I, [2024-11-13T22:27:01.153471 #344] INFO -- : Database: 'ci', Table: 'custom_fields': Lock Writes44422:27:01I, [2024-11-13T22:27:01.196031 #344] INFO -- : Database: 'ci', Table: 'custom_field_select_options': Lock Writes44522:27:01I, [2024-11-13T22:27:01.240053 #344] INFO -- : Database: 'ci', Table: 'work_item_type_custom_fields': Lock Writes44622:27:13I, [2024-11-13T22:27:13.583263 #344] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes44722:27:13I, [2024-11-13T22:27:13.966568 #344] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes44822:27:14I, [2024-11-13T22:27:14.497615 #344] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignment_versions': Lock Writes44922:27:14I, [2024-11-13T22:27:14.538211 #344] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes45022:27:14I, [2024-11-13T22:27:14.703367 #344] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes45122:27:15I, [2024-11-13T22:27:15.434775 #344] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes45222:27:15I, [2024-11-13T22:27:15.474977 #344] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes45322:27:15I, [2024-11-13T22:27:15.852569 #344] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes45422:27:16I, [2024-11-13T22:27:16.045697 #344] INFO -- : Database: 'ci', Table: 'members_deletion_schedules': Lock Writes45522:27:16I, [2024-11-13T22:27:16.461412 #344] INFO -- : Database: 'ci', Table: 'group_scim_identities': Lock Writes45622:27:16I, [2024-11-13T22:27:16.462809 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}45722:27:16I, [2024-11-13T22:27:16.464989 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}45822:27:16I, [2024-11-13T22:27:16.510176 #344] INFO -- : Database: 'ci', Table: 'group_scim_auth_access_tokens': Lock Writes45922:27:16I, [2024-11-13T22:27:16.511723 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}46022:27:16I, [2024-11-13T22:27:16.512735 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}46122:27:16I, [2024-11-13T22:27:16.942702 #344] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes46222:27:33# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ci_job_variables_project_id_spec.rb. It took 3 minutes 7.45 seconds. Expected to take 1 minute 29.26 seconds.46322:27:33# [RSpecRunTime] RSpec elapsed time: 10 minutes 8.37 seconds. Current RSS: ~927M. Threads: 1. load average: 1.15 1.70 3.45 1/262 43546522:27:33# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb. Expected to take 1 minute 16.35 seconds.46922:28:52I, [2024-11-13T22:28:52.071318 #344] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes47022:28:52I, [2024-11-13T22:28:52.072556 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}47122:28:52I, [2024-11-13T22:28:52.073424 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}47222:29:07I, [2024-11-13T22:29:07.291913 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes47322:29:15I, [2024-11-13T22:29:15.905926 #344] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes47422:29:25I, [2024-11-13T22:29:25.945869 #344] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes47522:29:28I, [2024-11-13T22:29:28.986487 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes47822:29:33INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows47922:29:33I, [2024-11-13T22:29:33.367728 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes48022:29:33I, [2024-11-13T22:29:33.369163 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}48122:29:33I, [2024-11-13T22:29:33.370049 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}48222:29:40I, [2024-11-13T22:29:40.775251 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes48322:29:53I, [2024-11-13T22:29:53.374389 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes48422:29:53I, [2024-11-13T22:29:53.375768 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}48522:29:53I, [2024-11-13T22:29:53.376795 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}48622:29:53I, [2024-11-13T22:29:53.380824 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes48722:29:53I, [2024-11-13T22:29:53.382123 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}48822:29:53I, [2024-11-13T22:29:53.383095 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}48922:29:53I, [2024-11-13T22:29:53.387237 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes49022:29:53I, [2024-11-13T22:29:53.388481 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}49122:29:53I, [2024-11-13T22:29:53.389502 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}49222:29:53I, [2024-11-13T22:29:53.393447 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes49322:29:53I, [2024-11-13T22:29:53.394698 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}49422:29:53I, [2024-11-13T22:29:53.395642 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}49522:29:54I, [2024-11-13T22:29:54.573598 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes49622:29:54I, [2024-11-13T22:29:54.575024 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}49722:29:54I, [2024-11-13T22:29:54.576178 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}49822:29:54I, [2024-11-13T22:29:54.579892 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes49922:29:54I, [2024-11-13T22:29:54.581181 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}50022:29:54I, [2024-11-13T22:29:54.582245 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}50122:29:54I, [2024-11-13T22:29:54.588274 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes50222:29:54I, [2024-11-13T22:29:54.589612 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}50322:29:54I, [2024-11-13T22:29:54.590617 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}50422:29:54I, [2024-11-13T22:29:54.594741 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes50522:29:54I, [2024-11-13T22:29:54.596004 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}50622:29:54I, [2024-11-13T22:29:54.597468 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}50722:30:16# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb. It took 2 minutes 43.08 seconds. Expected to take 1 minute 16.35 seconds.50822:30:16# [RSpecRunTime] RSpec elapsed time: 12 minutes 51.49 seconds. Current RSS: ~927M. Threads: 1. load average: 0.87 1.35 3.03 2/263 43651022:30:16# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_related_epic_links_group_id_spec.rb. Expected to take 1 minute 13.23 seconds.51422:31:46I, [2024-11-13T22:31:46.870090 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes51522:31:55I, [2024-11-13T22:31:55.852444 #344] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes51622:32:05I, [2024-11-13T22:32:05.887340 #344] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes51722:32:09I, [2024-11-13T22:32:09.062511 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes52022:32:13INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows52122:32:13I, [2024-11-13T22:32:13.546370 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes52222:32:13I, [2024-11-13T22:32:13.547766 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}52322:32:13I, [2024-11-13T22:32:13.548713 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}52422:32:20I, [2024-11-13T22:32:20.902897 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes52522:32:33I, [2024-11-13T22:32:33.576590 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes52622:32:33I, [2024-11-13T22:32:33.577967 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}52722:32:33I, [2024-11-13T22:32:33.578931 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}52822:32:33I, [2024-11-13T22:32:33.583003 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes52922:32:33I, [2024-11-13T22:32:33.584933 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}53022:32:33I, [2024-11-13T22:32:33.586020 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}53122:32:33I, [2024-11-13T22:32:33.590555 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes53222:32:33I, [2024-11-13T22:32:33.592038 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}53322:32:33I, [2024-11-13T22:32:33.593038 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}53422:32:33I, [2024-11-13T22:32:33.597343 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes53522:32:33I, [2024-11-13T22:32:33.598715 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}53622:32:33I, [2024-11-13T22:32:33.599669 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}53722:32:34I, [2024-11-13T22:32:34.757876 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes53822:32:34I, [2024-11-13T22:32:34.759193 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}53922:32:34I, [2024-11-13T22:32:34.760085 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}54022:32:34I, [2024-11-13T22:32:34.763641 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes54122:32:34I, [2024-11-13T22:32:34.764894 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}54222:32:34I, [2024-11-13T22:32:34.765782 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}54322:32:34I, [2024-11-13T22:32:34.769374 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes54422:32:34I, [2024-11-13T22:32:34.770573 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}54522:32:34I, [2024-11-13T22:32:34.771475 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}54622:32:34I, [2024-11-13T22:32:34.775272 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes54722:32:34I, [2024-11-13T22:32:34.776477 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}54822:32:34I, [2024-11-13T22:32:34.777447 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}54922:32:56# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_related_epic_links_group_id_spec.rb. It took 2 minutes 40.23 seconds. Expected to take 1 minute 13.23 seconds.55022:32:56# [RSpecRunTime] RSpec elapsed time: 15 minutes 31.77 seconds. Current RSS: ~931M. Threads: 1. load average: 0.89 1.14 2.68 1/262 43755222:32:56# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_sbom_occurrences_vulnerabilities_project_id_spec.rb. Expected to take 1 minute 8.96 seconds.55622:34:25I, [2024-11-13T22:34:25.050863 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes55722:34:33I, [2024-11-13T22:34:33.802861 #344] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes55822:34:43I, [2024-11-13T22:34:43.638794 #344] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes55922:34:46I, [2024-11-13T22:34:46.789497 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes56222:34:51INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows56322:34:51I, [2024-11-13T22:34:51.316310 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes56422:34:51I, [2024-11-13T22:34:51.317587 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}56522:34:51I, [2024-11-13T22:34:51.318498 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}56622:34:58I, [2024-11-13T22:34:58.635047 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes56722:35:11I, [2024-11-13T22:35:11.096927 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes56822:35:11I, [2024-11-13T22:35:11.098292 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}56922:35:11I, [2024-11-13T22:35:11.099307 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}57022:35:11I, [2024-11-13T22:35:11.103816 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes57122:35:11I, [2024-11-13T22:35:11.105143 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}57222:35:11I, [2024-11-13T22:35:11.106202 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}57322:35:11I, [2024-11-13T22:35:11.110192 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes57422:35:11I, [2024-11-13T22:35:11.111400 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}57522:35:11I, [2024-11-13T22:35:11.112371 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}57622:35:11I, [2024-11-13T22:35:11.116354 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes57722:35:11I, [2024-11-13T22:35:11.117584 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}57822:35:11I, [2024-11-13T22:35:11.118505 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}57922:35:12I, [2024-11-13T22:35:12.374185 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes58022:35:12I, [2024-11-13T22:35:12.375602 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}58122:35:12I, [2024-11-13T22:35:12.376615 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}58222:35:12I, [2024-11-13T22:35:12.380528 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes58322:35:12I, [2024-11-13T22:35:12.381890 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}58422:35:12I, [2024-11-13T22:35:12.382864 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}58522:35:12I, [2024-11-13T22:35:12.386971 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes58622:35:12I, [2024-11-13T22:35:12.388224 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}58722:35:12I, [2024-11-13T22:35:12.389129 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}58822:35:12I, [2024-11-13T22:35:12.393737 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes58922:35:12I, [2024-11-13T22:35:12.394996 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}59022:35:12I, [2024-11-13T22:35:12.395948 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}59122:35:34# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_sbom_occurrences_vulnerabilities_project_id_spec.rb. It took 2 minutes 37.59 seconds. Expected to take 1 minute 8.96 seconds.59222:35:34# [RSpecRunTime] RSpec elapsed time: 18 minutes 9.39 seconds. Current RSS: ~932M. Threads: 1. load average: 0.97 1.09 2.41 1/263 43859422:35:34# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb. Expected to take 58.19 seconds.59822:36:34I, [2024-11-13T22:36:34.004791 #344] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes59922:36:43I, [2024-11-13T22:36:43.571532 #344] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes60022:36:53I, [2024-11-13T22:36:53.147609 #344] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes60122:36:56I, [2024-11-13T22:36:56.289150 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes60422:37:00INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows60522:37:00I, [2024-11-13T22:37:00.725892 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes60622:37:00I, [2024-11-13T22:37:00.727357 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}60722:37:00I, [2024-11-13T22:37:00.728298 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}60822:37:07I, [2024-11-13T22:37:07.993516 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes60922:37:20I, [2024-11-13T22:37:20.606583 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes61022:37:20I, [2024-11-13T22:37:20.608279 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}61122:37:20I, [2024-11-13T22:37:20.609325 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}61222:37:20I, [2024-11-13T22:37:20.613543 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes61322:37:20I, [2024-11-13T22:37:20.614964 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}61422:37:20I, [2024-11-13T22:37:20.615934 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}61522:37:20I, [2024-11-13T22:37:20.620244 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes61622:37:20I, [2024-11-13T22:37:20.621620 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}61722:37:20I, [2024-11-13T22:37:20.622602 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}61822:37:20I, [2024-11-13T22:37:20.626860 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes61922:37:20I, [2024-11-13T22:37:20.628238 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}62022:37:20I, [2024-11-13T22:37:20.629280 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}62122:37:21I, [2024-11-13T22:37:21.820032 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes62222:37:21I, [2024-11-13T22:37:21.821273 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}62322:37:21I, [2024-11-13T22:37:21.822089 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}62422:37:21I, [2024-11-13T22:37:21.825744 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes62522:37:21I, [2024-11-13T22:37:21.826897 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}62622:37:21I, [2024-11-13T22:37:21.827699 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}62722:37:21I, [2024-11-13T22:37:21.831135 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes62822:37:21I, [2024-11-13T22:37:21.832366 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}62922:37:21I, [2024-11-13T22:37:21.833672 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}63022:37:21I, [2024-11-13T22:37:21.837587 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes63122:37:21I, [2024-11-13T22:37:21.838766 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}63222:37:21I, [2024-11-13T22:37:21.839600 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}63322:37:42# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb. It took 2 minutes 8.14 seconds. Expected to take 58.19 seconds.63422:37:42# [RSpecRunTime] RSpec elapsed time: 20 minutes 17.56 seconds. Current RSS: ~931M. Threads: 1. load average: 1.07 1.07 2.23 1/263 43963622:37:42# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ci_job_artifact_states_project_id_spec.rb. Expected to take 54.67 seconds.64022:38:52I, [2024-11-13T22:38:52.850518 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes64122:38:52I, [2024-11-13T22:38:52.851950 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}64222:38:52I, [2024-11-13T22:38:52.853072 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}64322:38:52I, [2024-11-13T22:38:52.857342 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes64422:38:52I, [2024-11-13T22:38:52.858702 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}64522:38:52I, [2024-11-13T22:38:52.859612 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}64622:38:52I, [2024-11-13T22:38:52.863996 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes64722:38:52I, [2024-11-13T22:38:52.865382 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}64822:38:52I, [2024-11-13T22:38:52.866457 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}64922:38:52I, [2024-11-13T22:38:52.870636 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes65022:38:52I, [2024-11-13T22:38:52.871964 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}65122:38:52I, [2024-11-13T22:38:52.873176 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}65222:38:54I, [2024-11-13T22:38:54.161121 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes65322:38:54I, [2024-11-13T22:38:54.162716 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}65422:38:54I, [2024-11-13T22:38:54.163728 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}65522:38:54I, [2024-11-13T22:38:54.167723 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes65622:38:54I, [2024-11-13T22:38:54.169078 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}65722:38:54I, [2024-11-13T22:38:54.170016 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}65822:38:54I, [2024-11-13T22:38:54.174050 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes65922:38:54I, [2024-11-13T22:38:54.175329 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}66022:38:54I, [2024-11-13T22:38:54.176429 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}66122:38:54I, [2024-11-13T22:38:54.180760 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes66222:38:54I, [2024-11-13T22:38:54.182026 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}66322:38:54I, [2024-11-13T22:38:54.183050 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}66422:39:08I, [2024-11-13T22:39:08.640252 #344] INFO -- : Database: 'ci', Table: 'duo_workflows_events': Lock Writes66522:39:08I, [2024-11-13T22:39:08.680275 #344] INFO -- : Database: 'ci', Table: 'wiki_page_meta_user_mentions': Lock Writes66622:39:08I, [2024-11-13T22:39:08.957526 #344] INFO -- : Database: 'ci', Table: 'packages_conan_package_revisions': Lock Writes66722:39:10I, [2024-11-13T22:39:10.214625 #344] INFO -- : Database: 'ci', Table: 'project_security_statistics': Lock Writes66822:39:10I, [2024-11-13T22:39:10.393081 #344] INFO -- : Database: 'ci', Table: 'custom_fields': Lock Writes66922:39:10I, [2024-11-13T22:39:10.437283 #344] INFO -- : Database: 'ci', Table: 'custom_field_select_options': Lock Writes67022:39:10I, [2024-11-13T22:39:10.480249 #344] INFO -- : Database: 'ci', Table: 'work_item_type_custom_fields': Lock Writes67122:39:23I, [2024-11-13T22:39:23.700430 #344] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes67222:39:24I, [2024-11-13T22:39:24.089747 #344] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes67322:39:24I, [2024-11-13T22:39:24.611639 #344] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignment_versions': Lock Writes67422:39:24I, [2024-11-13T22:39:24.649406 #344] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes67522:39:24I, [2024-11-13T22:39:24.817936 #344] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes67622:39:25I, [2024-11-13T22:39:25.549011 #344] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes67722:39:25I, [2024-11-13T22:39:25.591560 #344] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes67822:39:25I, [2024-11-13T22:39:25.979644 #344] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes67922:39:26I, [2024-11-13T22:39:26.199835 #344] INFO -- : Database: 'ci', Table: 'members_deletion_schedules': Lock Writes68022:39:26I, [2024-11-13T22:39:26.545399 #344] INFO -- : Database: 'ci', Table: 'group_scim_identities': Lock Writes68122:39:26I, [2024-11-13T22:39:26.546766 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}68222:39:26I, [2024-11-13T22:39:26.547657 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}68322:39:26I, [2024-11-13T22:39:26.583823 #344] INFO -- : Database: 'ci', Table: 'group_scim_auth_access_tokens': Lock Writes68422:39:26I, [2024-11-13T22:39:26.585313 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}68522:39:26I, [2024-11-13T22:39:26.586222 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}68622:39:26I, [2024-11-13T22:39:26.945595 #344] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes68722:39:43# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ci_job_artifact_states_project_id_spec.rb. It took 2 minutes 1.11 seconds. Expected to take 54.67 seconds.68822:39:43# [RSpecRunTime] RSpec elapsed time: 22 minutes 18.71 seconds. Current RSS: ~926M. Threads: 1. load average: 1.00 1.04 2.08 1/263 44069022:39:43# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_subscription_user_add_on_assignments_organization_id_spec.rb. Expected to take 44.52 seconds.69422:40:24I, [2024-11-13T22:40:24.719390 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes69722:40:30INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows69822:40:30I, [2024-11-13T22:40:30.074414 #344] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes69922:40:30I, [2024-11-13T22:40:30.076044 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}70022:40:30I, [2024-11-13T22:40:30.077046 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}70122:40:37I, [2024-11-13T22:40:37.389349 #344] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes70222:40:49I, [2024-11-13T22:40:49.913632 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes70322:40:49I, [2024-11-13T22:40:49.914953 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}70422:40:49I, [2024-11-13T22:40:49.915861 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}70522:40:49I, [2024-11-13T22:40:49.920080 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes70622:40:49I, [2024-11-13T22:40:49.921407 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}70722:40:49I, [2024-11-13T22:40:49.922409 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}70822:40:49I, [2024-11-13T22:40:49.926250 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes70922:40:49I, [2024-11-13T22:40:49.927435 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}71022:40:49I, [2024-11-13T22:40:49.928303 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}71122:40:49I, [2024-11-13T22:40:49.931950 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes71222:40:49I, [2024-11-13T22:40:49.933164 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}71322:40:49I, [2024-11-13T22:40:49.934101 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}71422:40:51I, [2024-11-13T22:40:51.155324 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes71522:40:51I, [2024-11-13T22:40:51.156762 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}71622:40:51I, [2024-11-13T22:40:51.157661 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}71722:40:51I, [2024-11-13T22:40:51.161451 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes71822:40:51I, [2024-11-13T22:40:51.162680 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}71922:40:51I, [2024-11-13T22:40:51.163549 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}72022:40:51I, [2024-11-13T22:40:51.168180 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes72122:40:51I, [2024-11-13T22:40:51.169463 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}72222:40:51I, [2024-11-13T22:40:51.170397 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}72322:40:51I, [2024-11-13T22:40:51.175221 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes72422:40:51I, [2024-11-13T22:40:51.176452 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}72522:40:51I, [2024-11-13T22:40:51.177330 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}72622:41:11# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_subscription_user_add_on_assignments_organization_id_spec.rb. It took 1 minute 28.09 seconds. Expected to take 44.52 seconds.72722:41:11# [RSpecRunTime] RSpec elapsed time: 23 minutes 46.84 seconds. Current RSS: ~930M. Threads: 1. load average: 0.95 1.00 1.97 1/263 44172922:41:11# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/batching_strategies/primary_key_batching_strategy_spec.rb. Expected to take 44.15 seconds.73422:41:23# [RSpecRunTime] RSpec elapsed time: 23 minutes 58.94 seconds. Current RSS: ~933M. Threads: 1. load average: 0.96 1.00 1.95 1/263 44273822:41:29# [RSpecRunTime] RSpec elapsed time: 24 minutes 4.32 seconds. Current RSS: ~936M. Threads: 1. load average: 0.89 0.98 1.94 1/263 44374222:41:34# [RSpecRunTime] RSpec elapsed time: 24 minutes 9.64 seconds. Current RSS: ~936M. Threads: 1. load average: 0.90 0.98 1.93 1/263 44474622:41:39# [RSpecRunTime] RSpec elapsed time: 24 minutes 15.0 seconds. Current RSS: ~936M. Threads: 1. load average: 0.90 0.98 1.93 1/263 44575222:41:50# [RSpecRunTime] RSpec elapsed time: 24 minutes 25.52 seconds. Current RSS: ~937M. Threads: 1. load average: 0.85 0.97 1.91 1/263 44675622:41:55# [RSpecRunTime] RSpec elapsed time: 24 minutes 30.83 seconds. Current RSS: ~937M. Threads: 1. load average: 0.86 0.97 1.91 1/263 44775822:42:07# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/batching_strategies/primary_key_batching_strategy_spec.rb. It took 55.95 seconds. Expected to take 44.15 seconds.75922:42:07# [RSpecRunTime] RSpec elapsed time: 24 minutes 42.82 seconds. Current RSS: ~930M. Threads: 1. load average: 0.80 0.95 1.89 1/263 44876122:42:07# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb. Expected to take 24.78 seconds.76522:42:45I, [2024-11-13T22:42:45.238898 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes76622:42:45I, [2024-11-13T22:42:45.240412 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}76722:42:45I, [2024-11-13T22:42:45.241537 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}76822:42:45I, [2024-11-13T22:42:45.246349 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes76922:42:45I, [2024-11-13T22:42:45.247643 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}77022:42:45I, [2024-11-13T22:42:45.248586 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}77122:42:45I, [2024-11-13T22:42:45.252692 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes77222:42:45I, [2024-11-13T22:42:45.253939 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}77322:42:45I, [2024-11-13T22:42:45.255248 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}77422:42:45I, [2024-11-13T22:42:45.259277 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes77522:42:45I, [2024-11-13T22:42:45.260509 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}77622:42:45I, [2024-11-13T22:42:45.261419 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}77722:42:46I, [2024-11-13T22:42:46.549140 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes77822:42:46I, [2024-11-13T22:42:46.550756 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}77922:42:46I, [2024-11-13T22:42:46.551826 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}78022:42:46I, [2024-11-13T22:42:46.556363 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes78122:42:46I, [2024-11-13T22:42:46.557702 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}78222:42:46I, [2024-11-13T22:42:46.558682 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}78322:42:46I, [2024-11-13T22:42:46.563303 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes78422:42:46I, [2024-11-13T22:42:46.564531 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}78522:42:46I, [2024-11-13T22:42:46.565424 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}78622:42:46I, [2024-11-13T22:42:46.569455 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes78722:42:46I, [2024-11-13T22:42:46.570780 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}78822:42:46I, [2024-11-13T22:42:46.571780 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}78922:43:06# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb. It took 59.06 seconds. Expected to take 24.78 seconds.79022:43:06# [RSpecRunTime] RSpec elapsed time: 25 minutes 41.92 seconds. Current RSS: ~937M. Threads: 1. load average: 0.89 0.95 1.83 1/263 44979222:43:06# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_members_request_accepted_at_spec.rb. Expected to take 16.85 seconds.79522:43:18 when `requested_at`, `invite_token`, `invite_accepted_at` and `request_accepted_at` are set to nil79722:43:23# [RSpecRunTime] RSpec elapsed time: 25 minutes 58.71 seconds. Current RSS: ~941M. Threads: 1. load average: 0.92 0.95 1.81 1/263 45079922:43:30I, [2024-11-13T22:43:30.937996 #344] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes80022:43:30I, [2024-11-13T22:43:30.939523 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}80122:43:30I, [2024-11-13T22:43:30.940633 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}80222:43:30I, [2024-11-13T22:43:30.946326 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes80322:43:30I, [2024-11-13T22:43:30.947955 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}80422:43:30I, [2024-11-13T22:43:30.949124 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}80522:43:30I, [2024-11-13T22:43:30.953638 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes80622:43:30I, [2024-11-13T22:43:30.954914 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}80722:43:30I, [2024-11-13T22:43:30.955826 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}80822:43:30I, [2024-11-13T22:43:30.959902 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes80922:43:30I, [2024-11-13T22:43:30.961186 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}81022:43:30I, [2024-11-13T22:43:30.962126 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}81122:43:32I, [2024-11-13T22:43:32.182547 #344] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes81222:43:32I, [2024-11-13T22:43:32.184032 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}81322:43:32I, [2024-11-13T22:43:32.185012 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}81422:43:32I, [2024-11-13T22:43:32.189623 #344] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes81522:43:32I, [2024-11-13T22:43:32.190924 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}81622:43:32I, [2024-11-13T22:43:32.191847 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}81722:43:32I, [2024-11-13T22:43:32.195716 #344] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes81822:43:32I, [2024-11-13T22:43:32.196904 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}81922:43:32I, [2024-11-13T22:43:32.197769 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}82022:43:32I, [2024-11-13T22:43:32.201735 #344] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes82122:43:32I, [2024-11-13T22:43:32.202965 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}82222:43:32I, [2024-11-13T22:43:32.203851 #344] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}82322:43:51# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_members_request_accepted_at_spec.rb. It took 45.1 seconds. Expected to take 16.85 seconds.82422:43:51# [RSpecRunTime] RSpec elapsed time: 26 minutes 27.05 seconds. Current RSS: ~936M. Threads: 1. load average: 0.95 0.96 1.79 1/263 45182622:43:51# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_issues_correct_work_item_type_id_spec.rb. Expected to take 12.92 seconds.83022:44:19# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_issues_correct_work_item_type_id_spec.rb. It took 27.31 seconds. Expected to take 12.92 seconds.83122:44:19# [RSpecRunTime] RSpec elapsed time: 26 minutes 54.4 seconds. Current RSS: ~930M. Threads: 1. load average: 0.97 0.96 1.76 1/263 45283322:44:19# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/resync_epic_dates_to_work_items_dates_sources_spec.rb. Expected to take 6.87 seconds.83722:44:26# [RSpecRunTime] RSpec elapsed time: 27 minutes 2.04 seconds. Current RSS: ~942M. Threads: 1. load average: 0.97 0.97 1.76 1/263 45383922:44:38# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/resync_epic_dates_to_work_items_dates_sources_spec.rb. It took 19.49 seconds. Expected to take 6.87 seconds.84022:44:38# [RSpecRunTime] RSpec elapsed time: 27 minutes 13.92 seconds. Current RSS: ~932M. Threads: 1. load average: 0.98 0.97 1.74 2/263 45484222:44:38# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_personal_access_token_seven_days_notification_sent_spec.rb. Expected to take 6.39 seconds.84622:44:44# [RSpecRunTime] RSpec elapsed time: 27 minutes 20.12 seconds. Current RSS: ~942M. Threads: 1. load average: 0.90 0.95 1.73 1/263 45584822:44:56# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_personal_access_token_seven_days_notification_sent_spec.rb. It took 18.06 seconds. Expected to take 6.39 seconds.84922:44:56# [RSpecRunTime] RSpec elapsed time: 27 minutes 32.02 seconds. Current RSS: ~937M. Threads: 1. load average: 0.84 0.94 1.72 1/263 45685522:44:56Gitlab::Backg...anizationUsers (./spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb:5) – 02:08.482 (91131 / 2) of 03:41.202 (58.08%)85622:44:56Gitlab::Backg...ablesProjectId (./spec/lib/gitlab/background_migration/backfill_ci_job_variables_project_id_spec.rb:5) – 01:44.276 (78812 / 2) of 03:07.479 (55.62%)85722:44:56Gitlab::Backg...FiltersGroupId (./spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb:5) – 01:32.279 (60432 / 2) of 02:43.126 (56.57%)85822:44:56Gitlab::Backg...icLinksGroupId (./spec/lib/gitlab/background_migration/backfill_related_epic_links_group_id_spec.rb:5) – 01:30.919 (58523 / 2) of 02:40.269 (56.73%)85922:44:56Gitlab::Backg...itiesProjectId (./spec/lib/gitlab/background_migration/backfill_sbom_occurrences_vulnerabilities_project_id_spec.rb:5) – 01:29.312 (58615 / 2) of 02:37.622 (56.66%)86322:44:56 "spec/lib/gitlab/background_migration/backfill_default_organization_users_spec.rb": 106.63462764499945,86422:44:56 "spec/lib/gitlab/background_migration/backfill_ci_job_variables_project_id_spec.rb": 92.71852669000054,86522:44:56 "spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb": 77.20627545600018,86622:44:56 "spec/lib/gitlab/background_migration/backfill_related_epic_links_group_id_spec.rb": 75.16522432200054,86722:44:56 "spec/lib/gitlab/background_migration/backfill_sbom_occurrences_vulnerabilities_project_id_spec.rb": 73.2313529789999,86822:44:56 "spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb": 58.96907662299964,86922:44:56 "spec/lib/gitlab/background_migration/backfill_ci_job_artifact_states_project_id_spec.rb": 56.74605740199968,87022:44:56 "spec/lib/gitlab/background_migration/backfill_subscription_user_add_on_assignments_organization_id_spec.rb": 39.9884068249994,87122:44:56 "spec/lib/gitlab/background_migration/batching_strategies/primary_key_batching_strategy_spec.rb": 43.99923219399989,87222:44:56 "spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb": 25.585298341999987,87322:44:56 "spec/lib/gitlab/background_migration/backfill_members_request_accepted_at_spec.rb": 16.793596025000625,87422:44:56 "spec/lib/gitlab/background_migration/backfill_issues_correct_work_item_type_id_spec.rb": 13.218532808999953,87522:44:56 "spec/lib/gitlab/background_migration/resync_epic_dates_to_work_items_dates_sources_spec.rb": 7.637057162999554,87622:44:56 "spec/lib/gitlab/background_migration/backfill_personal_access_token_seven_days_notification_sent_spec.rb": 6.1939301709999294922:45:04$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command95222:45:04$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command95822:45:04$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command96122:45:04$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command97022:45:07WARNING: auto_explain/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss) 97322:45:07WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss) 97622:45:07WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab-foss) 97822:45:07WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353300949/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com98022:45:09Uploading artifacts as "archive" to coordinator... 201 Created id=8353300949 responseStatus=201 Created token=glcbt-6698322:45:09WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8353300949/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com98522:45:11Uploading artifacts as "junit" to coordinator... 201 Created id=8353300949 responseStatus=201 Created token=glcbt-66