Skip to content
Snippets Groups Projects

rspec background_migration pg14 7/10

Passed Started by
Eduardo Sanz García
@eduardosanz
Log timestamps in UTC.
117:44:28Running with gitlab-runner 17.4.0~pre.110.g27400594 (27400594)
217:44:28 on blue-4.private.runners-manager.gitlab.com/gitlab.com/gitlab-org tDjA3Sp3, system ID: s_96a8ae3d1050
317:44:28 feature flags: FF_NETWORK_PER_BUILD:true, FF_TIMESTAMPS:true
417:44:28Resolving secrets
5 17:44:28 Preparing the "docker+machine" executor 00:08
617:44:29Using 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 ...
717:44:31Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1...
817:44:31Authenticating with credentials from job payload (GitLab Registry)
917:44:31Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...
1017:44:31Using 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 ...
1117:44:31WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1217:44:31WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.
1317:44:31Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12...
1417:44:31Authenticating with credentials from job payload (GitLab Registry)
1517:44:31Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...
1617:44:32Using 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 ...
1717:44:32Starting service redis:6.2-alpine...
1817:44:32Pulling docker image redis:6.2-alpine ...
1917:44:33Using docker image sha256:ec1b0a84ddf9124276ac189cc4d98ef28f0aea95ebd148d9e4c18b28928dd999 for redis:6.2-alpine with digest redis@sha256:2ba50e1ac3a0ea17b736ce9db2b0a9f6f8b85d4c27d5f5accc6a416d8f42c6d5 ...
2017:44:33Waiting for services to be up and running (timeout 30 seconds)...
2117:44:36Authenticating with credentials from job payload (GitLab Registry)
2217:44:36Pulling 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 ...
2317:44:36Using docker image sha256:31e981de8a825083695845b07ca34c01ce0f1c5b3e7d9e89e52e5b77c0df04a5 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:a5a9db043a920dcc296b68cfbed0958437128c3e391789aea0af2ac25d2a36db ...
24 17:44:36 Preparing environment 00:00
2517:44:36Running on runner-tdja3sp3-project-278964-concurrent-0 via runner-tdja3sp3-private-1730997256-a18f9d3f...
26 17:44:36 Getting source from Git repository 00:01
2717:44:37Skipping Git repository setup
2817:44:37Skipping Git checkout
2917:44:37Skipping Git submodules setup
30 17:44:37 Restoring cache 00:17
3117:44:37Checking cache for ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20...
3217:44:37cache.zip is up to date
3317:44:47Successfully extracted cache
34 17:44:54 Downloading artifacts 00:44
3517:44:54Downloading artifacts for clone-gitlab-repo (8298975602)...
3617:44:59Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8298975602 responseStatus=200 OK token=glcbt-66
3717:44:59WARNING: Part of .git directory is on the list of files to extract
3817:44:59WARNING: This may introduce unexpected problems
3917:45:12Downloading artifacts for compile-test-assets (8298975678)...
4017:45:17Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8298975678 responseStatus=200 OK token=glcbt-66
4117:45:20Downloading artifacts for detect-tests (8298975687)...
4217:45:21Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8298975687 responseStatus=200 OK token=glcbt-66
4317:45:21Downloading artifacts for retrieve-tests-metadata (8298975696)...
4417:45:21Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8298975696 responseStatus=200 OK token=glcbt-66
4517:45:21Downloading artifacts for setup-test-env (8298975682)...
4617:45:26Downloading artifacts from coordinator... ok host=storage.googleapis.com id=8298975682 responseStatus=200 OK token=glcbt-66
47 17:45:38 Executing "step_script" stage of the job script
4817:45:38Using docker image sha256:31e981de8a825083695845b07ca34c01ce0f1c5b3e7d9e89e52e5b77c0df04a5 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:a5a9db043a920dcc296b68cfbed0958437128c3e391789aea0af2ac25d2a36db ...
4917:45:39$ echo $FOSS_ONLY
5017:45:39$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb
5117:45:39$ export GOPATH=$CI_PROJECT_DIR/.go
5217:45:39$ mkdir -p $GOPATH
5317:45:39$ source scripts/utils.sh
5417:45:39$ log_disk_usage "true"
5517:45:39*******************************************************
5617:45:39This runner currently has 77G free disk space.
5717:45:39*******************************************************
58 17:45:39 Disk usage detail 00:12
5917:45:39df -h
6017:45:39Filesystem Size Used Avail Use% Mounted on
6117:45:39overlay 95G 19G 77G 20% /
6217:45:39tmpfs 64M 0 64M 0% /dev
6317:45:39shm 64M 0 64M 0% /dev/shm
6417:45:39/dev/sda1 95G 19G 77G 20% /cache
6517:45:39tmpfs 3.9G 0 3.9G 0% /sys/devices/virtual/dmi/id
6617:45:39du -h -d 1
6717:45:397.4M ./workhorse
6817:45:39148K ./bin
6917:45:391.2M ./.gitlab
7017:45:39176M ./.git
7117:45:394.0K ./builds
7217:45:3928M ./lib
7317:45:398.0K ./.vscode
7417:45:39560K ./storybook
7517:45:3920K ./log
7617:45:391.8M ./data
7717:45:3911M ./qa
7817:45:393.2M ./.rubocop_todo
7917:45:39817M ./vendor
8017:45:39681M ./public
8117:45:3960M ./doc
8217:45:39472K ./danger
8317:45:3940K ./rspec
8417:45:39548K ./tooling
8517:45:39145M ./ee
8617:45:391.3M ./knapsack
8717:45:39300K ./patches
8817:45:394.3M ./changelogs
8917:45:3920K ./haml_lint
9017:45:3928K ./metrics_server
9117:45:3996K ./keeps
9217:45:3936K ./shared
9317:45:3944K ./generator_templates
9417:45:391.3M ./scripts
9517:45:39764M ./tmp
9617:45:39127M ./spec
9717:45:3920K ./file_hooks
9817:45:3932K ./crystalball
9917:45:3916K ./sidekiq_cluster
10017:45:3917M ./gems
10117:45:391.2M ./fixtures
10217:45:39968K ./rubocop
10317:45:394.0K ./.go
10417:45:3932M ./db
10517:45:3912K ./.github
10617:45:39129M ./locale
10717:45:3920M ./config
10817:45:3981M ./app
10917:45:3920K ./.lefthook
11017:45:393.1G .
11117:45:39$ source scripts/prepare_build.sh
112 17:45:39 Installing gems 00:15
14317:45:54Using decomposed database config (config/database.yml.decomposed-postgresql)
14417:45:54Geo DB won't be set up.
14517:45:54Embedding DB won't be set up.
146 17:45:54 Setting up DBs 01:11
15917:47:05$ source ./scripts/rspec_helpers.sh
16017:47:05$ run_timed_command "gem install knapsack --no-document"
16117:47:05$ gem install knapsack --no-document
16217:47:07Successfully installed knapsack-4.0.0
16317:47:071 gem installed
16417:47:05==> 'gem install knapsack --no-document' succeeded in 2 seconds.
16517:47:07$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"
166 17:47:07 Spawning Gitaly 00:04
17017:47:11$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"
17117:47:11$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"
17217:47:11$ tooling/bin/create_job_metrics_file || true
17317:47:15[job-metrics] Creating the job metrics file for the CI/CD job.
17417:47:15$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"
175 17:47:15 RSpec 52:04
17617:47:15[17:47:15] Starting rspec_parallelized_job
17717:47:07RETRY_FAILED_TESTS_IN_NEW_PROCESS: true
17817:47:15KNAPSACK_GENERATE_REPORT: true
17917:47:15FLAKY_RSPEC_GENERATE_REPORT: true
18017:47:15KNAPSACK_TEST_FILE_PATTERN: spec/{lib/gitlab/background_migration,lib/ee/gitlab/background_migration}{,/**/}*_spec.rb
18117:47:15KNAPSACK_LOG_LEVEL: debug
18217:47:15KNAPSACK_REPORT_PATH: knapsack/rspec_background_migration_pg14_7_10_278964_report.json
18317:47:15FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json
18417:47:15FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_background_migration_pg14_7_10_278964_report.json
18517:47:15NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_background_migration_pg14_7_10_278964_report.json
18617:47:15RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-8298976152.txt
18717:47:15CRYSTALBALL:
18817:47:15RSPEC_TESTS_MAPPING_ENABLED:
18917:47:15RSPEC_TESTS_FILTER_FILE:
19017:47:15Shell set options (set -o) enabled:
19117:47:15braceexpand on
19217:47:15hashall on
19317:47:15interactive-comments on
19417:47:15pipefail on
19517:47:20Knapsack report generator started!
19617:47:15/builds/gitlab-org/gitlab/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!
19717:48:13DEPRECATION 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/config/environment.rb:7)
19817:48:24warning: parser/current is loading parser/ruby32, which recognizes 3.2.6-compliant syntax, but you are running 3.2.5.
19917:48:24Please see https://github.com/whitequark/parser#compatibility-with-ruby-mri.
20017:48:29Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}
20117:48:29# [RSpecRunTime] Starting RSpec timer...
20217:48:29[TEST PROF INFO] EventProf enabled (sql.active_record)
20317:48:29unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20417:48:29unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.
20517:48:41Test environment set up in 3.796490005 seconds
20617:48:41# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_design_management_repositories_spec.rb. Expected to take 2 minutes 50.07 seconds.
20717:48:41Gitlab::BackgroundMigration::BackfillDesignManagementRepositories
20817:50:26 #perform
20917:50:49 creates design_management_repositories entries for all projects in range
21017:50:49 when project_id already exists in design_management_repositories
21117:51:09 doesn't duplicate project_id
21217:51:12I, [2024-11-07T17:51:12.106014 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
21317:51:33I, [2024-11-07T17:51:33.974024 #341] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
21417:51:35INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
21517:51:35INFO: analyzing "public.ci_pipeline_variables"
21617:51:35INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
21717:51:35I, [2024-11-07T17:51:35.659959 #341] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
21817:51:35I, [2024-11-07T17:51:35.661823 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
21917:51:35I, [2024-11-07T17:51:35.662690 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22017:51:37INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
22117:51:37INFO: analyzing "public.ci_pipeline_variables"
22217:51:37INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
22317:51:42INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
22417:51:42INFO: analyzing "public.ci_job_artifacts"
22517:51:42INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
22617:51:42I, [2024-11-07T17:51:42.480376 #341] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
22717:51:42I, [2024-11-07T17:51:42.481596 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
22817:51:42I, [2024-11-07T17:51:42.482415 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
22917:51:45INFO: analyzing "public.p_ci_stages" inheritance tree
23017:51:45INFO: analyzing "public.ci_stages"
23117:51:45INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
23217:51:45I, [2024-11-07T17:51:45.929714 #341] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
23317:51:45I, [2024-11-07T17:51:45.930759 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23417:51:45I, [2024-11-07T17:51:45.931522 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
23517:51:47I, [2024-11-07T17:51:47.290547 #341] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
23617:51:54I, [2024-11-07T17:51:54.230592 #341] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
23717:52:08I, [2024-11-07T17:52:08.853066 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
23817:52:08I, [2024-11-07T17:52:08.854273 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
23917:52:08I, [2024-11-07T17:52:08.855981 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
24017:52:21I, [2024-11-07T17:52:21.833943 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
24117:52:29I, [2024-11-07T17:52:29.203148 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
24217:52:37I, [2024-11-07T17:52:37.633331 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
24317:52:40I, [2024-11-07T17:52:40.370646 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
24417:52:44INFO: analyzing "public.p_ci_pipelines" inheritance tree
24517:52:44INFO: analyzing "public.ci_pipelines"
24617:52:44INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
24717:52:44I, [2024-11-07T17:52:44.352727 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
24817:52:44I, [2024-11-07T17:52:44.353928 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
24917:52:44I, [2024-11-07T17:52:44.354741 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25017:52:50I, [2024-11-07T17:52:50.809292 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
25117:53:01I, [2024-11-07T17:53:01.630724 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
25217:53:01I, [2024-11-07T17:53:01.631902 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25317:53:01I, [2024-11-07T17:53:01.632684 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25417:53:01I, [2024-11-07T17:53:01.635972 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
25517:53:01I, [2024-11-07T17:53:01.637116 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25617:53:01I, [2024-11-07T17:53:01.637962 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
25717:53:01I, [2024-11-07T17:53:01.641940 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
25817:53:01I, [2024-11-07T17:53:01.644237 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
25917:53:01I, [2024-11-07T17:53:01.645125 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26017:53:01I, [2024-11-07T17:53:01.648819 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
26117:53:01I, [2024-11-07T17:53:01.649902 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26217:53:01I, [2024-11-07T17:53:01.650687 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26317:53:02I, [2024-11-07T17:53:02.638905 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
26417:53:02I, [2024-11-07T17:53:02.640081 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26517:53:02I, [2024-11-07T17:53:02.640923 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26617:53:02I, [2024-11-07T17:53:02.644704 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
26717:53:02I, [2024-11-07T17:53:02.645746 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
26817:53:02I, [2024-11-07T17:53:02.646608 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
26917:53:02I, [2024-11-07T17:53:02.649959 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
27017:53:02I, [2024-11-07T17:53:02.650997 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27117:53:02I, [2024-11-07T17:53:02.651762 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27217:53:02I, [2024-11-07T17:53:02.654922 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
27317:53:02I, [2024-11-07T17:53:02.655972 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
27417:53:02I, [2024-11-07T17:53:02.656733 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
27517:53:20# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_design_management_repositories_spec.rb. It took 4 minutes 38.22 seconds. Expected to take 2 minutes 50.07 seconds.
27617:53:20# [RSpecRunTime] RSpec elapsed time: 4 minutes 50.64 seconds. Current RSS: ~1235M. Threads: 1. load average: 1.03 1.17 1.34 1/276 387
27717:53:20.
27817:53:20# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_user_preferences_with_defaults_spec.rb. Expected to take 2 minutes 26.41 seconds.
27917:53:20Gitlab::BackgroundMigration::BackfillUserPreferencesWithDefaults
28017:55:19 backfills the null values with the default values
28117:55:20I, [2024-11-07T17:55:20.282368 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes
28217:55:42I, [2024-11-07T17:55:42.134018 #341] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes
28317:55:43INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
28417:55:43INFO: analyzing "public.ci_pipeline_variables"
28517:55:43INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
28617:55:43I, [2024-11-07T17:55:43.820538 #341] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes
28717:55:43I, [2024-11-07T17:55:43.821623 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
28817:55:43I, [2024-11-07T17:55:43.822371 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
28917:55:45INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
29017:55:45INFO: analyzing "public.ci_pipeline_variables"
29117:55:45INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29217:55:50INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
29317:55:50INFO: analyzing "public.ci_job_artifacts"
29417:55:50INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
29517:55:50I, [2024-11-07T17:55:50.857517 #341] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
29617:55:50I, [2024-11-07T17:55:50.858670 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
29717:55:50I, [2024-11-07T17:55:50.859537 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
29817:55:54INFO: analyzing "public.p_ci_stages" inheritance tree
29917:55:54INFO: analyzing "public.ci_stages"
30017:55:54INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
30117:55:54I, [2024-11-07T17:55:54.502151 #341] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
30217:55:54I, [2024-11-07T17:55:54.503299 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30317:55:54I, [2024-11-07T17:55:54.504138 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30417:55:55I, [2024-11-07T17:55:55.909767 #341] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
30517:56:02I, [2024-11-07T17:56:02.906947 #341] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
30617:56:17I, [2024-11-07T17:56:17.714918 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
30717:56:17I, [2024-11-07T17:56:17.716139 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
30817:56:17I, [2024-11-07T17:56:17.716941 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
30917:56:31I, [2024-11-07T17:56:31.166571 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
31017:56:38I, [2024-11-07T17:56:38.967010 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
31117:56:48I, [2024-11-07T17:56:48.080173 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
31217:56:51I, [2024-11-07T17:56:51.058325 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
31317:56:55INFO: analyzing "public.p_ci_pipelines" inheritance tree
31417:56:55INFO: analyzing "public.ci_pipelines"
31517:56:55INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
31617:56:55I, [2024-11-07T17:56:55.083170 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
31717:56:55I, [2024-11-07T17:56:55.084441 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
31817:56:55I, [2024-11-07T17:56:55.085279 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
31917:57:01I, [2024-11-07T17:57:01.066407 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
32017:57:12I, [2024-11-07T17:57:12.543868 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
32117:57:12I, [2024-11-07T17:57:12.545106 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32217:57:12I, [2024-11-07T17:57:12.545941 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32317:57:12I, [2024-11-07T17:57:12.549470 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
32417:57:12I, [2024-11-07T17:57:12.550616 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32517:57:12I, [2024-11-07T17:57:12.551460 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32617:57:12I, [2024-11-07T17:57:12.555584 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
32717:57:12I, [2024-11-07T17:57:12.556779 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
32817:57:12I, [2024-11-07T17:57:12.557680 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
32917:57:12I, [2024-11-07T17:57:12.561388 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
33017:57:12I, [2024-11-07T17:57:12.562584 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33117:57:12I, [2024-11-07T17:57:12.563458 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33217:57:13I, [2024-11-07T17:57:13.622502 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
33317:57:13I, [2024-11-07T17:57:13.623644 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33417:57:13I, [2024-11-07T17:57:13.624560 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33517:57:13I, [2024-11-07T17:57:13.627988 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
33617:57:13I, [2024-11-07T17:57:13.629093 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
33717:57:13I, [2024-11-07T17:57:13.629923 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
33817:57:13I, [2024-11-07T17:57:13.633006 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
33917:57:13I, [2024-11-07T17:57:13.634358 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34017:57:13I, [2024-11-07T17:57:13.635305 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34117:57:13I, [2024-11-07T17:57:13.638825 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
34217:57:13I, [2024-11-07T17:57:13.639975 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
34317:57:13I, [2024-11-07T17:57:13.640823 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
34417:57:31# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_user_preferences_with_defaults_spec.rb. It took 4 minutes 11.06 seconds. Expected to take 2 minutes 26.41 seconds.
34517:57:31# [RSpecRunTime] RSpec elapsed time: 9 minutes 2.16 seconds. Current RSS: ~1232M. Threads: 1. load average: 0.99 1.11 1.27 1/277 389
34617:57:31.
34717:57:31# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_or_drop_ci_pipeline_on_project_id_spec.rb. Expected to take 2 minutes 4.87 seconds.
34817:57:31Gitlab::BackgroundMigration::BackfillOrDropCiPipelineOnProjectId
34917:58:07 #perform
35017:59:10WARNING: Active Record does not support composite primary key.
35117:59:10p_ci_builds has composite primary key. Composite primary key is ignored.
35217:59:14 backfills if applicable otherwise deletes
35317:59:14 for migrations with merge_request
35417:59:16WARNING: Active Record does not support composite primary key.
35517:59:16p_ci_builds has composite primary key. Composite primary key is ignored.
35617:59:21 backfills from merge_request
35717:59:21# [RSpecRunTime] RSpec elapsed time: 10 minutes 51.5 seconds. Current RSS: ~1252M. Threads: 1. load average: 1.00 1.09 1.25 1/277 390
35817:59:21.
35917:59:21 when backfill will create an invalid record
36017:59:23WARNING: Active Record does not support composite primary key.
36117:59:23p_ci_builds has composite primary key. Composite primary key is ignored.
36217:59:27 deletes the pipeline instead
36317:59:27# [RSpecRunTime] RSpec elapsed time: 10 minutes 57.98 seconds. Current RSS: ~1247M. Threads: 1. load average: 1.00 1.09 1.25 1/277 391
36417:59:27.
36517:59:27 when associations are invalid as well
36617:59:29WARNING: Active Record does not support composite primary key.
36717:59:29p_ci_builds has composite primary key. Composite primary key is ignored.
36817:59:29WARNING: Active Record does not support composite primary key.
36917:59:29p_ci_builds has composite primary key. Composite primary key is ignored.
37017:59:33 deletes pipeline if associations do not have project_id
37117:59:34# [RSpecRunTime] RSpec elapsed time: 11 minutes 4.47 seconds. Current RSS: ~1250M. Threads: 1. load average: 0.92 1.07 1.24 1/277 392
37217:59:34.
37317:59:41I, [2024-11-07T17:59:41.017496 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
37417:59:43I, [2024-11-07T17:59:43.797667 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
37517:59:47INFO: analyzing "public.p_ci_pipelines" inheritance tree
37617:59:47INFO: analyzing "public.ci_pipelines"
37717:59:47INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
37817:59:47I, [2024-11-07T17:59:47.867005 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
37917:59:47I, [2024-11-07T17:59:47.868319 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
38017:59:47I, [2024-11-07T17:59:47.869227 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38117:59:54I, [2024-11-07T17:59:54.300312 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
38218:00:06I, [2024-11-07T18:00:06.012355 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
38318:00:06I, [2024-11-07T18:00:06.013647 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
38418:00:06I, [2024-11-07T18:00:06.014528 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38518:00:06I, [2024-11-07T18:00:06.018429 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
38618:00:06I, [2024-11-07T18:00:06.019599 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
38718:00:06I, [2024-11-07T18:00:06.020424 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
38818:00:06I, [2024-11-07T18:00:06.024318 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
38918:00:06I, [2024-11-07T18:00:06.025436 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39018:00:06I, [2024-11-07T18:00:06.026245 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39118:00:06I, [2024-11-07T18:00:06.029890 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
39218:00:06I, [2024-11-07T18:00:06.030965 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39318:00:06I, [2024-11-07T18:00:06.031827 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39418:00:07I, [2024-11-07T18:00:07.147218 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
39518:00:07I, [2024-11-07T18:00:07.148777 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39618:00:07I, [2024-11-07T18:00:07.149987 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
39718:00:07I, [2024-11-07T18:00:07.154351 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
39818:00:07I, [2024-11-07T18:00:07.155693 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
39918:00:07I, [2024-11-07T18:00:07.156695 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40018:00:07I, [2024-11-07T18:00:07.160948 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
40118:00:07I, [2024-11-07T18:00:07.162183 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40218:00:07I, [2024-11-07T18:00:07.163149 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40318:00:07I, [2024-11-07T18:00:07.167230 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
40418:00:07I, [2024-11-07T18:00:07.168452 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
40518:00:07I, [2024-11-07T18:00:07.169376 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
40618:00:29I, [2024-11-07T18:00:29.255223 #341] INFO -- : Database: 'ci', Table: 'project_secrets_managers': Lock Writes
40718:00:29I, [2024-11-07T18:00:29.334186 #341] INFO -- : Database: 'ci', Table: 'cluster_agent_url_configurations': Lock Writes
40818:00:29I, [2024-11-07T18:00:29.735348 #341] INFO -- : Database: 'ci', Table: 'observability_logs_issues_connections': Lock Writes
40918:00:30I, [2024-11-07T18:00:30.408703 #341] INFO -- : Database: 'ci', Table: 'vulnerability_namespace_historical_statistics': Lock Writes
41018:00:30I, [2024-11-07T18:00:30.810523 #341] INFO -- : Database: 'ci', Table: 'observability_traces_issues_connections': Lock Writes
41118:00:31I, [2024-11-07T18:00:31.565848 #341] INFO -- : Database: 'ci', Table: 'import_placeholder_memberships': Lock Writes
41218:00:32I, [2024-11-07T18:00:32.472757 #341] INFO -- : Database: 'ci', Table: 'ai_code_suggestion_events': Lock Writes
41318:00:32I, [2024-11-07T18:00:32.473965 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
41418:00:32I, [2024-11-07T18:00:32.474906 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
41518:00:32I, [2024-11-07T18:00:32.981773 #341] INFO -- : Database: 'ci', Table: 'security_policy_requirements': Lock Writes
41618:00:33I, [2024-11-07T18:00:33.355768 #341] INFO -- : Database: 'ci', Table: 'workspaces_agent_configs': Lock Writes
41718:00:33I, [2024-11-07T18:00:33.571102 #341] INFO -- : Database: 'ci', Table: 'workspaces_agent_config_versions': Lock Writes
41818:00:33I, [2024-11-07T18:00:33.770234 #341] INFO -- : Database: 'ci', Table: 'project_security_exclusions': Lock Writes
41918:00:33I, [2024-11-07T18:00:33.807589 #341] INFO -- : Database: 'ci', Table: 'group_security_exclusions': Lock Writes
42018:00:34I, [2024-11-07T18:00:34.159491 #341] INFO -- : Database: 'ci', Table: 'instance_integrations': Lock Writes
42118:00:39INFO: analyzing "public.p_ci_pipelines" inheritance tree
42218:00:39INFO: "ci_pipelines": scanned 1 of 1 pages, containing 0 live rows and 25 dead rows; 0 rows in sample, 0 estimated total rows
42318:00:39INFO: analyzing "public.ci_pipelines"
42418:00:39INFO: "ci_pipelines": scanned 1 of 1 pages, containing 0 live rows and 25 dead rows; 0 rows in sample, 0 estimated total rows
42518:00:47I, [2024-11-07T18:00:47.585485 #341] INFO -- : Database: 'ci', Table: 'subscription_seat_assignments': Lock Writes
42618:00:47I, [2024-11-07T18:00:47.626602 #341] INFO -- : Database: 'ci', Table: 'vulnerability_management_policy_rules': Lock Writes
42718:00:47I, [2024-11-07T18:00:47.666952 #341] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_applications': Lock Writes
42818:00:47I, [2024-11-07T18:00:47.706925 #341] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_graph_access_tokens': Lock Writes
42918:00:47I, [2024-11-07T18:00:47.776038 #341] INFO -- : Database: 'ci', Table: 'packages_conan_recipe_revisions': Lock Writes
43018:00:47I, [2024-11-07T18:00:47.966136 #341] INFO -- : Database: 'ci', Table: 'packages_conan_package_references': Lock Writes
43118:00:48I, [2024-11-07T18:00:48.304019 #341] INFO -- : Database: 'ci', Table: 'merge_request_merge_schedules': Lock Writes
43218:00:48I, [2024-11-07T18:00:48.856183 #341] INFO -- : Database: 'ci', Table: 'duo_workflows_events': Lock Writes
43318:00:48I, [2024-11-07T18:00:48.893355 #341] INFO -- : Database: 'ci', Table: 'wiki_page_meta_user_mentions': Lock Writes
43418:00:49I, [2024-11-07T18:00:49.146819 #341] INFO -- : Database: 'ci', Table: 'packages_conan_package_revisions': Lock Writes
43518:00:50I, [2024-11-07T18:00:50.415444 #341] INFO -- : Database: 'ci', Table: 'project_security_statistics': Lock Writes
43618:00:50I, [2024-11-07T18:00:50.620615 #341] INFO -- : Database: 'ci', Table: 'custom_fields': Lock Writes
43718:00:50I, [2024-11-07T18:00:50.670990 #341] INFO -- : Database: 'ci', Table: 'custom_field_select_options': Lock Writes
43818:00:50I, [2024-11-07T18:00:50.713708 #341] INFO -- : Database: 'ci', Table: 'work_item_type_custom_fields': Lock Writes
43918:01:02I, [2024-11-07T18:01:02.951359 #341] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes
44018:01:03I, [2024-11-07T18:01:03.224468 #341] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes
44118:01:03I, [2024-11-07T18:01:03.714051 #341] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes
44218:01:03I, [2024-11-07T18:01:03.880051 #341] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes
44318:01:04I, [2024-11-07T18:01:04.605455 #341] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes
44418:01:04I, [2024-11-07T18:01:04.644678 #341] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes
44518:01:05I, [2024-11-07T18:01:05.072077 #341] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes
44618:01:05I, [2024-11-07T18:01:05.922007 #341] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes
44718:01:20# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_or_drop_ci_pipeline_on_project_id_spec.rb. It took 3 minutes 48.55 seconds. Expected to take 2 minutes 4.87 seconds.
44818:01:20# [RSpecRunTime] RSpec elapsed time: 12 minutes 50.76 seconds. Current RSS: ~1246M. Threads: 1. load average: 0.86 1.02 1.20 1/276 393
44918:01:20.
45018:01:20# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_metadata_spec.rb. Expected to take 1 minute 57.61 seconds.
45118:01:20Gitlab::BackgroundMigration::BackfillPartitionIdCiPipelineMetadata
45218:02:53 #perform
45318:02:53 when second partition does not exist
45418:03:09 does not execute the migration
45518:03:09# [RSpecRunTime] RSpec elapsed time: 14 minutes 40.04 seconds. Current RSS: ~1292M. Threads: 1. load average: 1.06 1.05 1.19 1/277 394
45618:03:09.
45718:03:09 when second partition exists
45818:03:24 fixes invalid records in the wrong the partition
45918:03:24# [RSpecRunTime] RSpec elapsed time: 14 minutes 55.28 seconds. Current RSS: ~1263M. Threads: 1. load average: 1.05 1.05 1.18 1/277 395
46018:03:24.
46118:03:27INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree
46218:03:27INFO: analyzing "public.ci_pipeline_variables"
46318:03:27INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
46418:03:32INFO: analyzing "public.p_ci_job_artifacts" inheritance tree
46518:03:32INFO: analyzing "public.ci_job_artifacts"
46618:03:32INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
46718:03:32I, [2024-11-07T18:03:32.561396 #341] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes
46818:03:32I, [2024-11-07T18:03:32.562815 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
46918:03:32I, [2024-11-07T18:03:32.563806 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
47018:03:36INFO: analyzing "public.p_ci_stages" inheritance tree
47118:03:36INFO: analyzing "public.ci_stages"
47218:03:36INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
47318:03:36I, [2024-11-07T18:03:36.431250 #341] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes
47418:03:36I, [2024-11-07T18:03:36.432407 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
47518:03:36I, [2024-11-07T18:03:36.433205 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
47618:03:37I, [2024-11-07T18:03:37.915337 #341] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes
47718:03:45I, [2024-11-07T18:03:45.382048 #341] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes
47818:04:01I, [2024-11-07T18:04:01.399959 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
47918:04:01I, [2024-11-07T18:04:01.401601 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
48018:04:01I, [2024-11-07T18:04:01.402623 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
48118:04:15I, [2024-11-07T18:04:15.709183 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
48218:04:23I, [2024-11-07T18:04:23.953171 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
48318:04:33I, [2024-11-07T18:04:33.082592 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
48418:04:35I, [2024-11-07T18:04:35.940586 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
48518:04:40INFO: analyzing "public.p_ci_pipelines" inheritance tree
48618:04:40INFO: analyzing "public.ci_pipelines"
48718:04:40INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
48818:04:40I, [2024-11-07T18:04:40.224404 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
48918:04:40I, [2024-11-07T18:04:40.225767 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
49018:04:40I, [2024-11-07T18:04:40.226708 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
49118:04:46I, [2024-11-07T18:04:46.666156 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
49218:04:58I, [2024-11-07T18:04:58.629181 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
49318:04:58I, [2024-11-07T18:04:58.630373 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
49418:04:58I, [2024-11-07T18:04:58.631238 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
49518:04:58I, [2024-11-07T18:04:58.634818 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
49618:04:58I, [2024-11-07T18:04:58.635943 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
49718:04:58I, [2024-11-07T18:04:58.637061 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
49818:04:58I, [2024-11-07T18:04:58.640739 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
49918:04:58I, [2024-11-07T18:04:58.641907 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
50018:04:58I, [2024-11-07T18:04:58.642823 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
50118:04:58I, [2024-11-07T18:04:58.646373 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
50218:04:58I, [2024-11-07T18:04:58.647493 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
50318:04:58I, [2024-11-07T18:04:58.648383 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
50418:04:59I, [2024-11-07T18:04:59.684160 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
50518:04:59I, [2024-11-07T18:04:59.685719 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
50618:04:59I, [2024-11-07T18:04:59.686617 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
50718:04:59I, [2024-11-07T18:04:59.690182 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
50818:04:59I, [2024-11-07T18:04:59.691288 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
50918:04:59I, [2024-11-07T18:04:59.692193 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
51018:04:59I, [2024-11-07T18:04:59.695810 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
51118:04:59I, [2024-11-07T18:04:59.696906 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
51218:04:59I, [2024-11-07T18:04:59.697710 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
51318:04:59I, [2024-11-07T18:04:59.701056 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
51418:04:59I, [2024-11-07T18:04:59.702144 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
51518:04:59I, [2024-11-07T18:04:59.702959 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
51618:05:17# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_metadata_spec.rb. It took 3 minutes 56.83 seconds. Expected to take 1 minute 57.61 seconds.
51718:05:17# [RSpecRunTime] RSpec elapsed time: 16 minutes 47.64 seconds. Current RSS: ~1240M. Threads: 1. load average: 1.02 1.03 1.16 1/277 396
51818:05:17.
51918:05:17# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_approval_merge_request_rules_project_id_spec.rb. Expected to take 1 minute 37.89 seconds.
52018:05:17Gitlab::BackgroundMigration::BackfillApprovalMergeRequestRulesProjectId
52118:06:33 performs without error
52218:06:42 constructs a valid query
52318:06:57I, [2024-11-07T18:06:57.342332 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
52418:06:57I, [2024-11-07T18:06:57.343538 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
52518:06:57I, [2024-11-07T18:06:57.344338 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
52618:07:10I, [2024-11-07T18:07:10.107428 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
52718:07:17I, [2024-11-07T18:07:17.735819 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
52818:07:26I, [2024-11-07T18:07:26.229332 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
52918:07:28I, [2024-11-07T18:07:28.947837 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
53018:07:32INFO: analyzing "public.p_ci_pipelines" inheritance tree
53118:07:32INFO: analyzing "public.ci_pipelines"
53218:07:32INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
53318:07:32I, [2024-11-07T18:07:32.963311 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
53418:07:32I, [2024-11-07T18:07:32.964442 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
53518:07:32I, [2024-11-07T18:07:32.965216 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
53618:07:38I, [2024-11-07T18:07:38.863720 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
53718:07:50I, [2024-11-07T18:07:50.099182 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
53818:07:50I, [2024-11-07T18:07:50.100462 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
53918:07:50I, [2024-11-07T18:07:50.101385 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
54018:07:50I, [2024-11-07T18:07:50.104812 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
54118:07:50I, [2024-11-07T18:07:50.105944 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
54218:07:50I, [2024-11-07T18:07:50.106741 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
54318:07:50I, [2024-11-07T18:07:50.110022 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
54418:07:50I, [2024-11-07T18:07:50.111125 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
54518:07:50I, [2024-11-07T18:07:50.111961 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
54618:07:50I, [2024-11-07T18:07:50.115637 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
54718:07:50I, [2024-11-07T18:07:50.116726 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
54818:07:50I, [2024-11-07T18:07:50.117523 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
54918:07:51I, [2024-11-07T18:07:51.333309 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
55018:07:51I, [2024-11-07T18:07:51.334634 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
55118:07:51I, [2024-11-07T18:07:51.335494 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
55218:07:51I, [2024-11-07T18:07:51.338849 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
55318:07:51I, [2024-11-07T18:07:51.339923 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
55418:07:51I, [2024-11-07T18:07:51.340678 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
55518:07:51I, [2024-11-07T18:07:51.344369 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
55618:07:51I, [2024-11-07T18:07:51.345419 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
55718:07:51I, [2024-11-07T18:07:51.346176 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
55818:07:51I, [2024-11-07T18:07:51.349364 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
55918:07:51I, [2024-11-07T18:07:51.350462 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
56018:07:51I, [2024-11-07T18:07:51.351276 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
56118:08:08# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_approval_merge_request_rules_project_id_spec.rb. It took 2 minutes 51.2 seconds. Expected to take 1 minute 37.89 seconds.
56218:08:08# [RSpecRunTime] RSpec elapsed time: 19 minutes 38.89 seconds. Current RSS: ~1243M. Threads: 1. load average: 1.00 1.00 1.12 1/278 397
56318:08:08.
56418:08:08# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ci_build_needs_project_id_spec.rb. Expected to take 1 minute 31.92 seconds.
56518:08:08Gitlab::BackgroundMigration::BackfillCiBuildNeedsProjectId
56618:09:24 constructs a valid query
56718:09:29 performs without error
56818:09:31I, [2024-11-07T18:09:31.542260 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
56918:09:35INFO: analyzing "public.p_ci_pipelines" inheritance tree
57018:09:35INFO: analyzing "public.ci_pipelines"
57118:09:35INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
57218:09:35I, [2024-11-07T18:09:35.630972 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
57318:09:35I, [2024-11-07T18:09:35.632400 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
57418:09:35I, [2024-11-07T18:09:35.633337 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
57518:09:41I, [2024-11-07T18:09:41.735929 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
57618:09:52I, [2024-11-07T18:09:52.493105 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
57718:09:52I, [2024-11-07T18:09:52.494277 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
57818:09:52I, [2024-11-07T18:09:52.495115 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
57918:09:52I, [2024-11-07T18:09:52.498581 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
58018:09:52I, [2024-11-07T18:09:52.500208 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
58118:09:52I, [2024-11-07T18:09:52.501378 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
58218:09:52I, [2024-11-07T18:09:52.505370 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
58318:09:52I, [2024-11-07T18:09:52.506543 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
58418:09:52I, [2024-11-07T18:09:52.507392 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
58518:09:52I, [2024-11-07T18:09:52.510808 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
58618:09:52I, [2024-11-07T18:09:52.511931 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
58718:09:52I, [2024-11-07T18:09:52.512708 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
58818:09:53I, [2024-11-07T18:09:53.500429 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
58918:09:53I, [2024-11-07T18:09:53.501645 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
59018:09:53I, [2024-11-07T18:09:53.502558 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
59118:09:53I, [2024-11-07T18:09:53.506070 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
59218:09:53I, [2024-11-07T18:09:53.507430 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
59318:09:53I, [2024-11-07T18:09:53.508242 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
59418:09:53I, [2024-11-07T18:09:53.511696 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
59518:09:53I, [2024-11-07T18:09:53.512830 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
59618:09:53I, [2024-11-07T18:09:53.513690 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
59718:09:53I, [2024-11-07T18:09:53.517226 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
59818:09:53I, [2024-11-07T18:09:53.518458 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
59918:09:53I, [2024-11-07T18:09:53.519343 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
60018:10:05I, [2024-11-07T18:10:05.040153 #341] INFO -- : Database: 'ci', Table: 'security_policy_requirements': Lock Writes
60118:10:05I, [2024-11-07T18:10:05.413186 #341] INFO -- : Database: 'ci', Table: 'workspaces_agent_configs': Lock Writes
60218:10:05I, [2024-11-07T18:10:05.636727 #341] INFO -- : Database: 'ci', Table: 'workspaces_agent_config_versions': Lock Writes
60318:10:05I, [2024-11-07T18:10:05.823603 #341] INFO -- : Database: 'ci', Table: 'project_security_exclusions': Lock Writes
60418:10:05I, [2024-11-07T18:10:05.899245 #341] INFO -- : Database: 'ci', Table: 'group_security_exclusions': Lock Writes
60518:10:06I, [2024-11-07T18:10:06.250725 #341] INFO -- : Database: 'ci', Table: 'instance_integrations': Lock Writes
60618:10:11INFO: analyzing "public.p_ci_pipelines" inheritance tree
60718:10:11INFO: "ci_pipelines": scanned 1 of 1 pages, containing 0 live rows and 32 dead rows; 0 rows in sample, 0 estimated total rows
60818:10:11INFO: analyzing "public.ci_pipelines"
60918:10:11INFO: "ci_pipelines": scanned 1 of 1 pages, containing 0 live rows and 32 dead rows; 0 rows in sample, 0 estimated total rows
61018:10:18I, [2024-11-07T18:10:18.942604 #341] INFO -- : Database: 'ci', Table: 'subscription_seat_assignments': Lock Writes
61118:10:18I, [2024-11-07T18:10:18.981506 #341] INFO -- : Database: 'ci', Table: 'vulnerability_management_policy_rules': Lock Writes
61218:10:19I, [2024-11-07T18:10:19.043367 #341] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_applications': Lock Writes
61318:10:19I, [2024-11-07T18:10:19.080151 #341] INFO -- : Database: 'ci', Table: 'system_access_instance_microsoft_graph_access_tokens': Lock Writes
61418:10:19I, [2024-11-07T18:10:19.157292 #341] INFO -- : Database: 'ci', Table: 'packages_conan_recipe_revisions': Lock Writes
61518:10:19I, [2024-11-07T18:10:19.335569 #341] INFO -- : Database: 'ci', Table: 'packages_conan_package_references': Lock Writes
61618:10:19I, [2024-11-07T18:10:19.634089 #341] INFO -- : Database: 'ci', Table: 'merge_request_merge_schedules': Lock Writes
61718:10:20I, [2024-11-07T18:10:20.111019 #341] INFO -- : Database: 'ci', Table: 'duo_workflows_events': Lock Writes
61818:10:20I, [2024-11-07T18:10:20.147653 #341] INFO -- : Database: 'ci', Table: 'wiki_page_meta_user_mentions': Lock Writes
61918:10:20I, [2024-11-07T18:10:20.371426 #341] INFO -- : Database: 'ci', Table: 'packages_conan_package_revisions': Lock Writes
62018:10:21I, [2024-11-07T18:10:21.454271 #341] INFO -- : Database: 'ci', Table: 'project_security_statistics': Lock Writes
62118:10:21I, [2024-11-07T18:10:21.616348 #341] INFO -- : Database: 'ci', Table: 'custom_fields': Lock Writes
62218:10:21I, [2024-11-07T18:10:21.652159 #341] INFO -- : Database: 'ci', Table: 'custom_field_select_options': Lock Writes
62318:10:21I, [2024-11-07T18:10:21.691168 #341] INFO -- : Database: 'ci', Table: 'work_item_type_custom_fields': Lock Writes
62418:10:32I, [2024-11-07T18:10:32.463824 #341] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes
62518:10:32I, [2024-11-07T18:10:32.699406 #341] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes
62618:10:33I, [2024-11-07T18:10:33.110380 #341] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes
62718:10:33I, [2024-11-07T18:10:33.260285 #341] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes
62818:10:33I, [2024-11-07T18:10:33.959512 #341] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes
62918:10:34I, [2024-11-07T18:10:34.007830 #341] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes
63018:10:34I, [2024-11-07T18:10:34.341322 #341] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes
63118:10:35I, [2024-11-07T18:10:35.076073 #341] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes
63218:10:47# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ci_build_needs_project_id_spec.rb. It took 2 minutes 39.54 seconds. Expected to take 1 minute 31.92 seconds.
63318:10:48# [RSpecRunTime] RSpec elapsed time: 22 minutes 18.48 seconds. Current RSS: ~1244M. Threads: 1. load average: 1.19 1.10 1.14 1/278 398
63418:10:48.
63518:10:48# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_vulnerability_merge_request_links_project_id_spec.rb. Expected to take 1 minute 20.76 seconds.
63618:10:48Gitlab::BackgroundMigration::BackfillVulnerabilityMergeRequestLinksProjectId
63718:11:49 constructs a valid query
63818:11:57 performs without error
63918:12:10I, [2024-11-07T18:12:10.741451 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
64018:12:18I, [2024-11-07T18:12:18.302371 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
64118:12:26I, [2024-11-07T18:12:26.803583 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
64218:12:29I, [2024-11-07T18:12:29.540959 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
64318:12:33INFO: analyzing "public.p_ci_pipelines" inheritance tree
64418:12:33INFO: analyzing "public.ci_pipelines"
64518:12:33INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
64618:12:33I, [2024-11-07T18:12:33.616640 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
64718:12:33I, [2024-11-07T18:12:33.617774 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
64818:12:33I, [2024-11-07T18:12:33.618633 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
64918:12:39I, [2024-11-07T18:12:39.703847 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
65018:12:50I, [2024-11-07T18:12:50.898407 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
65118:12:50I, [2024-11-07T18:12:50.899508 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
65218:12:50I, [2024-11-07T18:12:50.900468 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
65318:12:50I, [2024-11-07T18:12:50.903920 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
65418:12:50I, [2024-11-07T18:12:50.905061 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
65518:12:50I, [2024-11-07T18:12:50.907359 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
65618:12:50I, [2024-11-07T18:12:50.911608 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
65718:12:50I, [2024-11-07T18:12:50.912678 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
65818:12:50I, [2024-11-07T18:12:50.913474 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
65918:12:50I, [2024-11-07T18:12:50.918393 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
66018:12:50I, [2024-11-07T18:12:50.919659 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
66118:12:50I, [2024-11-07T18:12:50.920587 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
66218:12:52I, [2024-11-07T18:12:52.047142 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
66318:12:52I, [2024-11-07T18:12:52.048330 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
66418:12:52I, [2024-11-07T18:12:52.049175 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
66518:12:52I, [2024-11-07T18:12:52.052561 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
66618:12:52I, [2024-11-07T18:12:52.053669 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
66718:12:52I, [2024-11-07T18:12:52.054504 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
66818:12:52I, [2024-11-07T18:12:52.057880 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
66918:12:52I, [2024-11-07T18:12:52.058949 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
67018:12:52I, [2024-11-07T18:12:52.059742 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
67118:12:52I, [2024-11-07T18:12:52.062970 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
67218:12:52I, [2024-11-07T18:12:52.064076 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
67318:12:52I, [2024-11-07T18:12:52.064908 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
67418:13:09# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_vulnerability_merge_request_links_project_id_spec.rb. It took 2 minutes 21.16 seconds. Expected to take 1 minute 20.76 seconds.
67518:13:09# [RSpecRunTime] RSpec elapsed time: 24 minutes 39.7 seconds. Current RSS: ~1255M. Threads: 1. load average: 1.09 1.10 1.13 1/278 399
67618:13:09.
67718:13:09# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_issue_links_namespace_id_spec.rb. Expected to take 1 minute 17.93 seconds.
67818:13:09Gitlab::BackgroundMigration::BackfillIssueLinksNamespaceId
67918:14:09 constructs a valid query
68018:14:17 performs without error
68118:14:30I, [2024-11-07T18:14:30.467020 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
68218:14:38I, [2024-11-07T18:14:38.294209 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
68318:14:47I, [2024-11-07T18:14:47.098521 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
68418:14:49I, [2024-11-07T18:14:49.939482 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
68518:14:54INFO: analyzing "public.p_ci_pipelines" inheritance tree
68618:14:54INFO: analyzing "public.ci_pipelines"
68718:14:54INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
68818:14:54I, [2024-11-07T18:14:54.254472 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
68918:14:54I, [2024-11-07T18:14:54.255605 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
69018:14:54I, [2024-11-07T18:14:54.256400 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
69118:15:00I, [2024-11-07T18:15:00.488189 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
69218:15:12I, [2024-11-07T18:15:12.004920 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
69318:15:12I, [2024-11-07T18:15:12.006305 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
69418:15:12I, [2024-11-07T18:15:12.007222 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
69518:15:12I, [2024-11-07T18:15:12.010727 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
69618:15:12I, [2024-11-07T18:15:12.011886 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
69718:15:12I, [2024-11-07T18:15:12.012707 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
69818:15:12I, [2024-11-07T18:15:12.016210 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
69918:15:12I, [2024-11-07T18:15:12.017404 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
70018:15:12I, [2024-11-07T18:15:12.018280 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
70118:15:12I, [2024-11-07T18:15:12.021964 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
70218:15:12I, [2024-11-07T18:15:12.023097 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
70318:15:12I, [2024-11-07T18:15:12.023961 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
70418:15:13I, [2024-11-07T18:15:13.081201 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
70518:15:13I, [2024-11-07T18:15:13.082398 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
70618:15:13I, [2024-11-07T18:15:13.083829 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
70718:15:13I, [2024-11-07T18:15:13.087229 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
70818:15:13I, [2024-11-07T18:15:13.088335 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
70918:15:13I, [2024-11-07T18:15:13.089176 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
71018:15:13I, [2024-11-07T18:15:13.092448 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
71118:15:13I, [2024-11-07T18:15:13.093563 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
71218:15:13I, [2024-11-07T18:15:13.094384 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
71318:15:13I, [2024-11-07T18:15:13.097774 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
71418:15:13I, [2024-11-07T18:15:13.098871 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
71518:15:13I, [2024-11-07T18:15:13.099644 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
71618:15:31# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_issue_links_namespace_id_spec.rb. It took 2 minutes 22.18 seconds. Expected to take 1 minute 17.93 seconds.
71718:15:31# [RSpecRunTime] RSpec elapsed time: 27 minutes 1.93 seconds. Current RSS: ~1244M. Threads: 1. load average: 1.01 1.08 1.12 1/278 400
71818:15:31.
71918:15:31# [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 14.21 seconds.
72018:15:31Gitlab::BackgroundMigration::BackfillAuditEventsStreamingEventTypeFiltersGroupId
72118:16:34 performs without error
72218:16:42 constructs a valid query
72318:16:44I, [2024-11-07T18:16:44.017183 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
72418:16:44I, [2024-11-07T18:16:44.018312 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
72518:16:44I, [2024-11-07T18:16:44.019088 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
72618:16:56I, [2024-11-07T18:16:56.796551 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
72718:17:04I, [2024-11-07T18:17:04.513222 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
72818:17:13I, [2024-11-07T18:17:13.363046 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
72918:17:16I, [2024-11-07T18:17:16.157870 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
73018:17:20INFO: analyzing "public.p_ci_pipelines" inheritance tree
73118:17:20INFO: analyzing "public.ci_pipelines"
73218:17:20INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
73318:17:20I, [2024-11-07T18:17:20.375092 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
73418:17:20I, [2024-11-07T18:17:20.376408 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
73518:17:20I, [2024-11-07T18:17:20.377529 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
73618:17:26I, [2024-11-07T18:17:26.458371 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
73718:17:37I, [2024-11-07T18:17:37.754150 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
73818:17:37I, [2024-11-07T18:17:37.755375 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
73918:17:37I, [2024-11-07T18:17:37.756264 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
74018:17:37I, [2024-11-07T18:17:37.759731 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
74118:17:37I, [2024-11-07T18:17:37.760858 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
74218:17:37I, [2024-11-07T18:17:37.761665 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
74318:17:37I, [2024-11-07T18:17:37.765397 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
74418:17:37I, [2024-11-07T18:17:37.766537 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
74518:17:37I, [2024-11-07T18:17:37.767353 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
74618:17:37I, [2024-11-07T18:17:37.771056 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
74718:17:37I, [2024-11-07T18:17:37.772182 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
74818:17:37I, [2024-11-07T18:17:37.773044 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
74918:17:38I, [2024-11-07T18:17:38.841477 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
75018:17:38I, [2024-11-07T18:17:38.842970 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
75118:17:38I, [2024-11-07T18:17:38.844158 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
75218:17:38I, [2024-11-07T18:17:38.848646 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
75318:17:38I, [2024-11-07T18:17:38.849863 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
75418:17:38I, [2024-11-07T18:17:38.850705 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
75518:17:38I, [2024-11-07T18:17:38.854433 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
75618:17:38I, [2024-11-07T18:17:38.855588 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
75718:17:38I, [2024-11-07T18:17:38.856450 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
75818:17:38I, [2024-11-07T18:17:38.859980 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
75918:17:38I, [2024-11-07T18:17:38.861095 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
76018:17:38I, [2024-11-07T18:17:38.861914 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
76118:17:55# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb. It took 2 minutes 24.43 seconds. Expected to take 1 minute 14.21 seconds.
76218:17:55# [RSpecRunTime] RSpec elapsed time: 29 minutes 26.41 seconds. Current RSS: ~1245M. Threads: 1. load average: 1.06 1.08 1.11 1/278 401
76318:17:55.
76418:17:55# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_agent_activity_events_agent_project_id_spec.rb. Expected to take 1 minute 12.94 seconds.
76518:17:55Gitlab::BackgroundMigration::BackfillAgentActivityEventsAgentProjectId
76618:18:57 performs without error
76718:19:05 constructs a valid query
76818:19:06I, [2024-11-07T18:19:06.265687 #341] INFO -- : Database: 'main', Table: 'p_ci_build_sources': Lock Writes
76918:19:06I, [2024-11-07T18:19:06.266908 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
77018:19:06I, [2024-11-07T18:19:06.267718 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
77118:19:19I, [2024-11-07T18:19:19.033364 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
77218:19:26I, [2024-11-07T18:19:26.602229 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
77318:19:35I, [2024-11-07T18:19:35.023868 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
77418:19:37I, [2024-11-07T18:19:37.754562 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
77518:19:41INFO: analyzing "public.p_ci_pipelines" inheritance tree
77618:19:41INFO: analyzing "public.ci_pipelines"
77718:19:41INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
77818:19:41I, [2024-11-07T18:19:41.953841 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
77918:19:41I, [2024-11-07T18:19:41.955041 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
78018:19:41I, [2024-11-07T18:19:41.955921 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
78118:19:48I, [2024-11-07T18:19:48.126976 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
78218:19:59I, [2024-11-07T18:19:59.407749 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
78318:19:59I, [2024-11-07T18:19:59.408954 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
78418:19:59I, [2024-11-07T18:19:59.409822 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
78518:19:59I, [2024-11-07T18:19:59.413262 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
78618:19:59I, [2024-11-07T18:19:59.414338 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
78718:19:59I, [2024-11-07T18:19:59.415142 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
78818:19:59I, [2024-11-07T18:19:59.418673 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
78918:19:59I, [2024-11-07T18:19:59.419809 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
79018:19:59I, [2024-11-07T18:19:59.420627 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
79118:19:59I, [2024-11-07T18:19:59.424209 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
79218:19:59I, [2024-11-07T18:19:59.425249 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
79318:19:59I, [2024-11-07T18:19:59.426092 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
79418:20:00I, [2024-11-07T18:20:00.577750 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
79518:20:00I, [2024-11-07T18:20:00.579067 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
79618:20:00I, [2024-11-07T18:20:00.579972 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
79718:20:00I, [2024-11-07T18:20:00.584480 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
79818:20:00I, [2024-11-07T18:20:00.585675 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
79918:20:00I, [2024-11-07T18:20:00.586529 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
80018:20:00I, [2024-11-07T18:20:00.590159 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
80118:20:00I, [2024-11-07T18:20:00.591256 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
80218:20:00I, [2024-11-07T18:20:00.592172 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
80318:20:00I, [2024-11-07T18:20:00.595906 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
80418:20:00I, [2024-11-07T18:20:00.597061 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
80518:20:00I, [2024-11-07T18:20:00.597930 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
80618:20:17# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_agent_activity_events_agent_project_id_spec.rb. It took 2 minutes 21.75 seconds. Expected to take 1 minute 12.94 seconds.
80718:20:17# [RSpecRunTime] RSpec elapsed time: 31 minutes 48.21 seconds. Current RSS: ~1253M. Threads: 1. load average: 0.90 1.06 1.09 1/279 402
80818:20:17.
80918:20:17# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb. Expected to take 1 minute 5.93 seconds.
81018:20:17Gitlab::BackgroundMigration::BackfillMlCandidateMetadataProjectId
81118:21:05 constructs a valid query
81218:21:12 performs without error
81318:21:13I, [2024-11-07T18:21:13.741870 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
81418:21:21I, [2024-11-07T18:21:21.344909 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
81518:21:30I, [2024-11-07T18:21:30.153371 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
81618:21:32I, [2024-11-07T18:21:32.972987 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
81718:21:37INFO: analyzing "public.p_ci_pipelines" inheritance tree
81818:21:37INFO: analyzing "public.ci_pipelines"
81918:21:37INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
82018:21:37I, [2024-11-07T18:21:37.385668 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
82118:21:37I, [2024-11-07T18:21:37.387020 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
82218:21:37I, [2024-11-07T18:21:37.387947 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
82318:21:43I, [2024-11-07T18:21:43.747875 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
82418:21:55I, [2024-11-07T18:21:55.192939 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
82518:21:55I, [2024-11-07T18:21:55.194209 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
82618:21:55I, [2024-11-07T18:21:55.195038 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
82718:21:55I, [2024-11-07T18:21:55.198600 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
82818:21:55I, [2024-11-07T18:21:55.199803 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
82918:21:55I, [2024-11-07T18:21:55.200635 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
83018:21:55I, [2024-11-07T18:21:55.204670 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
83118:21:55I, [2024-11-07T18:21:55.205762 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
83218:21:55I, [2024-11-07T18:21:55.206592 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
83318:21:55I, [2024-11-07T18:21:55.210149 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
83418:21:55I, [2024-11-07T18:21:55.211237 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
83518:21:55I, [2024-11-07T18:21:55.212062 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
83618:21:56I, [2024-11-07T18:21:56.300809 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
83718:21:56I, [2024-11-07T18:21:56.301993 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
83818:21:56I, [2024-11-07T18:21:56.302891 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
83918:21:56I, [2024-11-07T18:21:56.306335 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
84018:21:56I, [2024-11-07T18:21:56.307444 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
84118:21:56I, [2024-11-07T18:21:56.308257 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
84218:21:56I, [2024-11-07T18:21:56.311643 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
84318:21:56I, [2024-11-07T18:21:56.312709 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
84418:21:56I, [2024-11-07T18:21:56.313815 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
84518:21:56I, [2024-11-07T18:21:56.317083 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
84618:21:56I, [2024-11-07T18:21:56.318185 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
84718:21:56I, [2024-11-07T18:21:56.319009 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
84818:22:13# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb. It took 1 minute 55.32 seconds. Expected to take 1 minute 5.93 seconds.
84918:22:13# [RSpecRunTime] RSpec elapsed time: 33 minutes 43.58 seconds. Current RSS: ~1253M. Threads: 1. load average: 0.96 1.08 1.09 1/279 403
85018:22:13.
85118:22:13# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb. Expected to take 1 minute 2.89 seconds.
85218:22:13Gitlab::BackgroundMigration::BackfillPackagesDebianProjectArchitecturesProjectId
85318:23:01 performs without error
85418:23:08 constructs a valid query
85518:23:09I, [2024-11-07T18:23:09.175645 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
85618:23:17I, [2024-11-07T18:23:17.147862 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
85718:23:26I, [2024-11-07T18:23:26.062774 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
85818:23:28I, [2024-11-07T18:23:28.847437 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
85918:23:33INFO: analyzing "public.p_ci_pipelines" inheritance tree
86018:23:33INFO: analyzing "public.ci_pipelines"
86118:23:33INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
86218:23:33I, [2024-11-07T18:23:33.185254 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
86318:23:33I, [2024-11-07T18:23:33.186436 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
86418:23:33I, [2024-11-07T18:23:33.187349 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
86518:23:39I, [2024-11-07T18:23:39.134672 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
86618:23:50I, [2024-11-07T18:23:50.331270 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
86718:23:50I, [2024-11-07T18:23:50.332614 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
86818:23:50I, [2024-11-07T18:23:50.333566 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
86918:23:50I, [2024-11-07T18:23:50.337452 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
87018:23:50I, [2024-11-07T18:23:50.338620 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
87118:23:50I, [2024-11-07T18:23:50.339483 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
87218:23:50I, [2024-11-07T18:23:50.343273 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
87318:23:50I, [2024-11-07T18:23:50.344475 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
87418:23:50I, [2024-11-07T18:23:50.345341 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
87518:23:50I, [2024-11-07T18:23:50.349165 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
87618:23:50I, [2024-11-07T18:23:50.350270 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
87718:23:50I, [2024-11-07T18:23:50.351138 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
87818:23:51I, [2024-11-07T18:23:51.610502 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
87918:23:51I, [2024-11-07T18:23:51.611761 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
88018:23:51I, [2024-11-07T18:23:51.612683 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
88118:23:51I, [2024-11-07T18:23:51.616225 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
88218:23:51I, [2024-11-07T18:23:51.617341 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
88318:23:51I, [2024-11-07T18:23:51.618202 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
88418:23:51I, [2024-11-07T18:23:51.621811 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
88518:23:51I, [2024-11-07T18:23:51.622893 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
88618:23:51I, [2024-11-07T18:23:51.623694 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
88718:23:51I, [2024-11-07T18:23:51.627657 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
88818:23:51I, [2024-11-07T18:23:51.628722 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
88918:23:51I, [2024-11-07T18:23:51.629596 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
89018:24:08# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb. It took 1 minute 55.41 seconds. Expected to take 1 minute 2.89 seconds.
89118:24:08# [RSpecRunTime] RSpec elapsed time: 35 minutes 39.05 seconds. Current RSS: ~1255M. Threads: 1. load average: 1.04 1.05 1.08 1/279 404
89218:24:08.
89318:24:08# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_protected_tag_create_access_levels_project_id_spec.rb. Expected to take 55.56 seconds.
89418:24:08Gitlab::BackgroundMigration::BackfillProtectedTagCreateAccessLevelsProjectId
89518:24:57 constructs a valid query
89618:25:04 performs without error
89718:25:05I, [2024-11-07T18:25:05.915200 #341] INFO -- : Database: 'main', Table: 'p_ci_finished_pipeline_ch_sync_events': Lock Writes
89818:25:13I, [2024-11-07T18:25:13.902185 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
89918:25:23I, [2024-11-07T18:25:23.065841 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
90018:25:25I, [2024-11-07T18:25:25.872287 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
90118:25:30INFO: analyzing "public.p_ci_pipelines" inheritance tree
90218:25:30INFO: analyzing "public.ci_pipelines"
90318:25:30INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
90418:25:30I, [2024-11-07T18:25:30.149339 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
90518:25:30I, [2024-11-07T18:25:30.150800 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
90618:25:30I, [2024-11-07T18:25:30.151814 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
90718:25:36I, [2024-11-07T18:25:36.381020 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
90818:25:47I, [2024-11-07T18:25:47.724165 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
90918:25:47I, [2024-11-07T18:25:47.725384 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
91018:25:47I, [2024-11-07T18:25:47.726188 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
91118:25:47I, [2024-11-07T18:25:47.729572 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
91218:25:47I, [2024-11-07T18:25:47.730654 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
91318:25:47I, [2024-11-07T18:25:47.731448 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
91418:25:47I, [2024-11-07T18:25:47.734743 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
91518:25:47I, [2024-11-07T18:25:47.735840 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
91618:25:47I, [2024-11-07T18:25:47.736620 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
91718:25:47I, [2024-11-07T18:25:47.739889 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
91818:25:47I, [2024-11-07T18:25:47.740931 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
91918:25:47I, [2024-11-07T18:25:47.741720 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
92018:25:48I, [2024-11-07T18:25:48.812744 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
92118:25:48I, [2024-11-07T18:25:48.813914 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
92218:25:48I, [2024-11-07T18:25:48.814683 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
92318:25:48I, [2024-11-07T18:25:48.817894 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
92418:25:48I, [2024-11-07T18:25:48.818983 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
92518:25:48I, [2024-11-07T18:25:48.819765 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
92618:25:48I, [2024-11-07T18:25:48.822864 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
92718:25:48I, [2024-11-07T18:25:48.823937 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
92818:25:48I, [2024-11-07T18:25:48.824646 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
92918:25:48I, [2024-11-07T18:25:48.828197 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
93018:25:48I, [2024-11-07T18:25:48.829208 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
93118:25:48I, [2024-11-07T18:25:48.829981 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
93218:26:05# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_protected_tag_create_access_levels_project_id_spec.rb. It took 1 minute 57.01 seconds. Expected to take 55.56 seconds.
93318:26:05# [RSpecRunTime] RSpec elapsed time: 37 minutes 36.11 seconds. Current RSS: ~1259M. Threads: 1. load average: 1.13 1.10 1.09 1/278 405
93418:26:05.
93518:26:05# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb. Expected to take 52.49 seconds.
93618:26:05Gitlab::BackgroundMigration::BackfillEvidencesProjectId
93718:26:47 constructs a valid query
93818:26:53 performs without error
93918:27:02I, [2024-11-07T18:27:02.583517 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
94018:27:05I, [2024-11-07T18:27:05.361984 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
94118:27:09INFO: analyzing "public.p_ci_pipelines" inheritance tree
94218:27:09INFO: analyzing "public.ci_pipelines"
94318:27:09INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
94418:27:09I, [2024-11-07T18:27:09.711832 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
94518:27:09I, [2024-11-07T18:27:09.713104 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
94618:27:09I, [2024-11-07T18:27:09.713961 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
94718:27:15I, [2024-11-07T18:27:15.925582 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
94818:27:28I, [2024-11-07T18:27:28.232549 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
94918:27:28I, [2024-11-07T18:27:28.233725 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
95018:27:28I, [2024-11-07T18:27:28.234548 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
95118:27:28I, [2024-11-07T18:27:28.237882 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
95218:27:28I, [2024-11-07T18:27:28.238948 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
95318:27:28I, [2024-11-07T18:27:28.239767 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
95418:27:28I, [2024-11-07T18:27:28.243178 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
95518:27:28I, [2024-11-07T18:27:28.244268 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
95618:27:28I, [2024-11-07T18:27:28.245044 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
95718:27:28I, [2024-11-07T18:27:28.248355 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
95818:27:28I, [2024-11-07T18:27:28.249481 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
95918:27:28I, [2024-11-07T18:27:28.250278 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
96018:27:29I, [2024-11-07T18:27:29.310758 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
96118:27:29I, [2024-11-07T18:27:29.311995 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
96218:27:29I, [2024-11-07T18:27:29.312894 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
96318:27:29I, [2024-11-07T18:27:29.316434 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
96418:27:29I, [2024-11-07T18:27:29.317535 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
96518:27:29I, [2024-11-07T18:27:29.318351 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
96618:27:29I, [2024-11-07T18:27:29.322078 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
96718:27:29I, [2024-11-07T18:27:29.323187 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
96818:27:29I, [2024-11-07T18:27:29.324083 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
96918:27:29I, [2024-11-07T18:27:29.327578 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
97018:27:29I, [2024-11-07T18:27:29.328680 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
97118:27:29I, [2024-11-07T18:27:29.329502 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
97218:27:46# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb. It took 1 minute 40.35 seconds. Expected to take 52.49 seconds.
97318:27:46# [RSpecRunTime] RSpec elapsed time: 39 minutes 16.55 seconds. Current RSS: ~1249M. Threads: 1. load average: 1.09 1.10 1.09 1/278 406
97418:27:46.
97518:27:46# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_sharding_key_id_on_ci_runners_spec.rb. Expected to take 46.13 seconds.
97618:27:46Gitlab::BackgroundMigration::BackfillShardingKeyIdOnCiRunners
97718:27:54 #perform
97818:28:16 backfills sharding_key_id
97918:28:16 when a project runner is shared with other projects
98018:28:21 backfills sharding_key_id with the id of the owner project
98118:28:21 when the project has a different owner project
98218:28:26 backfills sharding_key_id with the id of the owner project
98318:28:26# [RSpecRunTime] RSpec elapsed time: 39 minutes 57.24 seconds. Current RSS: ~1244M. Threads: 1. load average: 0.89 1.05 1.07 1/278 407
98418:28:26.
98518:28:32I, [2024-11-07T18:28:32.476322 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
98618:28:32I, [2024-11-07T18:28:32.491106 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
98718:28:32I, [2024-11-07T18:28:32.492405 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
98818:28:32I, [2024-11-07T18:28:32.496635 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
98918:28:32I, [2024-11-07T18:28:32.497975 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
99018:28:32I, [2024-11-07T18:28:32.498891 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
99118:28:32I, [2024-11-07T18:28:32.502533 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
99218:28:32I, [2024-11-07T18:28:32.503658 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
99318:28:32I, [2024-11-07T18:28:32.504505 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
99418:28:32I, [2024-11-07T18:28:32.508176 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
99518:28:32I, [2024-11-07T18:28:32.509305 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
99618:28:32I, [2024-11-07T18:28:32.510150 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
99718:28:33I, [2024-11-07T18:28:33.617998 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
99818:28:33I, [2024-11-07T18:28:33.619097 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
99918:28:33I, [2024-11-07T18:28:33.620010 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
100018:28:33I, [2024-11-07T18:28:33.623352 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
100118:28:33I, [2024-11-07T18:28:33.624438 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
100218:28:33I, [2024-11-07T18:28:33.625258 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
100318:28:33I, [2024-11-07T18:28:33.628656 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
100418:28:33I, [2024-11-07T18:28:33.629694 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
100518:28:33I, [2024-11-07T18:28:33.630521 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
100618:28:33I, [2024-11-07T18:28:33.634011 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
100718:28:33I, [2024-11-07T18:28:33.635070 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
100818:28:33I, [2024-11-07T18:28:33.635856 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
100918:28:49I, [2024-11-07T18:28:49.926026 #341] INFO -- : Database: 'ci', Table: 'catalog_resource_component_last_usages': Lock Writes
101018:28:50I, [2024-11-07T18:28:50.177745 #341] INFO -- : Database: 'ci', Table: 'pm_cve_enrichment': Lock Writes
101118:28:50I, [2024-11-07T18:28:50.677590 #341] INFO -- : Database: 'ci', Table: 'pipl_users': Lock Writes
101218:28:50I, [2024-11-07T18:28:50.848414 #341] INFO -- : Database: 'ci', Table: 'vulnerability_representation_information': Lock Writes
101318:28:51I, [2024-11-07T18:28:51.764341 #341] INFO -- : Database: 'ci', Table: 'abuse_report_labels': Lock Writes
101418:28:51I, [2024-11-07T18:28:51.800406 #341] INFO -- : Database: 'ci', Table: 'abuse_report_label_links': Lock Writes
101518:28:52I, [2024-11-07T18:28:52.127106 #341] INFO -- : Database: 'ci', Table: 'work_item_weights_sources': Lock Writes
101618:28:52I, [2024-11-07T18:28:52.880413 #341] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_stage_aggregations': Lock Writes
101718:29:05# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_sharding_key_id_on_ci_runners_spec.rb. It took 1 minute 19.79 seconds. Expected to take 46.13 seconds.
101818:29:05# [RSpecRunTime] RSpec elapsed time: 40 minutes 36.42 seconds. Current RSS: ~1242M. Threads: 1. load average: 0.95 1.04 1.07 1/278 408
101918:29:05.
102018:29:05# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/resync_basic_epic_fields_to_work_item_spec.rb. Expected to take 43.1 seconds.
102118:29:05Gitlab::BackgroundMigration::ResyncBasicEpicFieldsToWorkItem
102218:29:43 when backfilling all epics
102318:29:51 backfills the work items for specific group only
102418:29:51# [RSpecRunTime] RSpec elapsed time: 41 minutes 21.59 seconds. Current RSS: ~1274M. Threads: 1. load average: 1.07 1.07 1.08 2/279 409
102518:29:51.
102618:29:53I, [2024-11-07T18:29:53.353109 #341] INFO -- : Database: 'main', Table: 'p_ci_build_tags': Lock Writes
102718:30:02I, [2024-11-07T18:30:02.426613 #341] INFO -- : Database: 'main', Table: 'p_ci_build_trace_metadata': Lock Writes
102818:30:05I, [2024-11-07T18:30:05.322863 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines_config': Lock Writes
102918:30:09INFO: analyzing "public.p_ci_pipelines" inheritance tree
103018:30:09INFO: analyzing "public.ci_pipelines"
103118:30:09INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
103218:30:09I, [2024-11-07T18:30:09.758251 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
103318:30:09I, [2024-11-07T18:30:09.759682 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
103418:30:09I, [2024-11-07T18:30:09.760630 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
103518:30:16I, [2024-11-07T18:30:16.059135 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
103618:30:27I, [2024-11-07T18:30:27.782713 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
103718:30:27I, [2024-11-07T18:30:27.783947 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
103818:30:27I, [2024-11-07T18:30:27.784776 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
103918:30:27I, [2024-11-07T18:30:27.788239 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
104018:30:27I, [2024-11-07T18:30:27.789365 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
104118:30:27I, [2024-11-07T18:30:27.790196 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
104218:30:27I, [2024-11-07T18:30:27.794139 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
104318:30:27I, [2024-11-07T18:30:27.795264 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
104418:30:27I, [2024-11-07T18:30:27.796198 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
104518:30:27I, [2024-11-07T18:30:27.799734 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
104618:30:27I, [2024-11-07T18:30:27.801075 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
104718:30:27I, [2024-11-07T18:30:27.801928 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
104818:30:28I, [2024-11-07T18:30:28.904622 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
104918:30:28I, [2024-11-07T18:30:28.906317 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
105018:30:28I, [2024-11-07T18:30:28.907226 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
105118:30:28I, [2024-11-07T18:30:28.910887 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
105218:30:28I, [2024-11-07T18:30:28.912033 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
105318:30:28I, [2024-11-07T18:30:28.912929 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
105418:30:28I, [2024-11-07T18:30:28.916456 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
105518:30:28I, [2024-11-07T18:30:28.917621 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
105618:30:28I, [2024-11-07T18:30:28.918492 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
105718:30:28I, [2024-11-07T18:30:28.922159 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
105818:30:28I, [2024-11-07T18:30:28.923308 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
105918:30:28I, [2024-11-07T18:30:28.924169 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
106018:30:47# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/resync_basic_epic_fields_to_work_item_spec.rb. It took 1 minute 41.11 seconds. Expected to take 43.1 seconds.
106118:30:47# [RSpecRunTime] RSpec elapsed time: 42 minutes 17.59 seconds. Current RSS: ~1254M. Threads: 1. load average: 1.02 1.05 1.07 1/279 410
106218:30:47.
106318:30:47# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/recalculate_dora_deployment_frequency_score_spec.rb. Expected to take 32.61 seconds.
106418:30:47Gitlab::BackgroundMigration::RecalculateDoraDeploymentFrequencyScore
106518:31:11 #perform
106618:31:17 updates deployment frequency score with correct value
106718:31:17# [RSpecRunTime] RSpec elapsed time: 42 minutes 48.41 seconds. Current RSS: ~1275M. Threads: 1. load average: 1.19 1.09 1.09 1/279 411
106818:31:17.
106918:31:21INFO: analyzing "public.p_ci_pipelines" inheritance tree
107018:31:21INFO: analyzing "public.ci_pipelines"
107118:31:21INFO: "ci_pipelines": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows
107218:31:21I, [2024-11-07T18:31:21.567479 #341] INFO -- : Database: 'main', Table: 'p_ci_pipelines': Lock Writes
107318:31:21I, [2024-11-07T18:31:21.568937 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
107418:31:21I, [2024-11-07T18:31:21.569890 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
107518:31:27I, [2024-11-07T18:31:27.928563 #341] INFO -- : Database: 'main', Table: 'ci_job_token_authorizations': Lock Writes
107618:31:39I, [2024-11-07T18:31:39.085411 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
107718:31:39I, [2024-11-07T18:31:39.086641 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
107818:31:39I, [2024-11-07T18:31:39.087519 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
107918:31:39I, [2024-11-07T18:31:39.091664 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
108018:31:39I, [2024-11-07T18:31:39.092899 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
108118:31:39I, [2024-11-07T18:31:39.093769 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
108218:31:39I, [2024-11-07T18:31:39.097344 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
108318:31:39I, [2024-11-07T18:31:39.098487 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
108418:31:39I, [2024-11-07T18:31:39.099343 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
108518:31:39I, [2024-11-07T18:31:39.102944 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
108618:31:39I, [2024-11-07T18:31:39.104121 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
108718:31:39I, [2024-11-07T18:31:39.105035 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
108818:31:40I, [2024-11-07T18:31:40.225585 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
108918:31:40I, [2024-11-07T18:31:40.226805 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
109018:31:40I, [2024-11-07T18:31:40.227723 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
109118:31:40I, [2024-11-07T18:31:40.231238 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
109218:31:40I, [2024-11-07T18:31:40.232396 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
109318:31:40I, [2024-11-07T18:31:40.233362 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
109418:31:40I, [2024-11-07T18:31:40.237009 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
109518:31:40I, [2024-11-07T18:31:40.238113 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
109618:31:40I, [2024-11-07T18:31:40.238998 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
109718:31:40I, [2024-11-07T18:31:40.243020 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
109818:31:40I, [2024-11-07T18:31:40.244155 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
109918:31:40I, [2024-11-07T18:31:40.244960 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
110018:31:57# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/recalculate_dora_deployment_frequency_score_spec.rb. It took 1 minute 10.05 seconds. Expected to take 32.61 seconds.
110118:31:57# [RSpecRunTime] RSpec elapsed time: 43 minutes 27.69 seconds. Current RSS: ~1257M. Threads: 1. load average: 1.14 1.09 1.09 1/279 412
110218:31:57.
110318:31:57# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_dast_profiles_pipelines_project_id_spec.rb. Expected to take 24.87 seconds.
110418:31:57Gitlab::BackgroundMigration::BackfillDastProfilesPipelinesProjectId
110518:32:14 constructs a valid query
110618:32:19 performs without error
110718:32:29I, [2024-11-07T18:32:29.624672 #341] INFO -- : Database: 'main', Table: 'ci_runners_e59bb2812d': Lock Writes
110818:32:29I, [2024-11-07T18:32:29.625936 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
110918:32:29I, [2024-11-07T18:32:29.626901 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
111018:32:29I, [2024-11-07T18:32:29.630429 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runners_e59bb2812d': Lock Writes
111118:32:29I, [2024-11-07T18:32:29.631746 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
111218:32:29I, [2024-11-07T18:32:29.632548 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
111318:32:29I, [2024-11-07T18:32:29.636000 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runners_e59bb2812d': Lock Writes
111418:32:29I, [2024-11-07T18:32:29.637098 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
111518:32:29I, [2024-11-07T18:32:29.637924 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
111618:32:29I, [2024-11-07T18:32:29.641226 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runners_e59bb2812d': Lock Writes
111718:32:29I, [2024-11-07T18:32:29.642340 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
111818:32:29I, [2024-11-07T18:32:29.643160 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
111918:32:30I, [2024-11-07T18:32:30.727693 #341] INFO -- : Database: 'main', Table: 'ci_runner_machines_687967fa8a': Lock Writes
112018:32:30I, [2024-11-07T18:32:30.728919 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
112118:32:30I, [2024-11-07T18:32:30.729753 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
112218:32:30I, [2024-11-07T18:32:30.733243 #341] INFO -- : Database: 'main', Table: 'instance_type_ci_runner_machines_687967fa8a': Lock Writes
112318:32:30I, [2024-11-07T18:32:30.734314 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
112418:32:30I, [2024-11-07T18:32:30.735130 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
112518:32:30I, [2024-11-07T18:32:30.738457 #341] INFO -- : Database: 'main', Table: 'group_type_ci_runner_machines_687967fa8a': Lock Writes
112618:32:30I, [2024-11-07T18:32:30.739559 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
112718:32:30I, [2024-11-07T18:32:30.740374 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
112818:32:30I, [2024-11-07T18:32:30.743749 #341] INFO -- : Database: 'main', Table: 'project_type_ci_runner_machines_687967fa8a': Lock Writes
112918:32:30I, [2024-11-07T18:32:30.744840 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}
113018:32:30I, [2024-11-07T18:32:30.745583 #341] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}
113118:32:47# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_dast_profiles_pipelines_project_id_spec.rb. It took 49.87 seconds. Expected to take 24.87 seconds.
113218:32:47# [RSpecRunTime] RSpec elapsed time: 44 minutes 17.61 seconds. Current RSS: ~1261M. Threads: 1. load average: 1.06 1.08 1.08 1/279 413
113318:32:47.
113418:32:47# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb. Expected to take 23.41 seconds.
113518:32:47Gitlab::BackgroundMigration::BackfillPackagesNugetMetadataProjectId
113618:33:04 performs without error
113718:33:09 constructs a valid query
113818:33:09The application_settings (main) table has 1207 columns.
113918:33:09Recreating the database
114018:33:10Dropped database 'gitlabhq_test'
114118:33:11Dropped database 'gitlabhq_test_ci'
114218:33:12Created database 'gitlabhq_test'
114318:33:12Created database 'gitlabhq_test_ci'
114418:33:24main: == [advisory_lock_connection] object_id: 449077220, pg_backend_pid: 4778
114518:33:24main: == [advisory_lock_connection] object_id: 449077220, pg_backend_pid: 4778
114618:33:24ci: == [advisory_lock_connection] object_id: 449105640, pg_backend_pid: 4780
114718:33:25ci: == [advisory_lock_connection] object_id: 449105640, pg_backend_pid: 4780
114818:33:33Databases re-creation done in 23.150140832000034
114918:33:33# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb. It took 46.17 seconds. Expected to take 23.41 seconds.
115018:33:33# [RSpecRunTime] RSpec elapsed time: 45 minutes 3.83 seconds. Current RSS: ~1257M. Threads: 1. load average: 1.14 1.08 1.08 1/279 416
115118:33:33.
115218:33:33# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/migrate_os_sbom_occurrences_to_components_without_prefix_spec.rb. Expected to take 17.43 seconds.
115318:33:33Gitlab::BackgroundMigration::MigrateOsSbomOccurrencesToComponentsWithoutPrefix
115418:33:34 when sbom occurrence belongs to sbom component with os prefix
115518:33:39 migrates the data correctly
115618:33:39 when components have no versions
115718:33:44 does not raise error
115818:33:44# [RSpecRunTime] RSpec elapsed time: 45 minutes 14.94 seconds. Current RSS: ~1266M. Threads: 1. load average: 0.96 1.05 1.07 1/279 417
115918:33:44.
116018:33:44 when components have no occurrences
116118:33:49 does not raise an error
116218:33:49# [RSpecRunTime] RSpec elapsed time: 45 minutes 19.9 seconds. Current RSS: ~1271M. Threads: 1. load average: 0.96 1.05 1.07 1/279 418
116318:33:49.
116418:34:00# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/migrate_os_sbom_occurrences_to_components_without_prefix_spec.rb. It took 27.34 seconds. Expected to take 17.43 seconds.
116518:34:00# [RSpecRunTime] RSpec elapsed time: 45 minutes 31.23 seconds. Current RSS: ~1259M. Threads: 1. load average: 1.04 1.06 1.08 1/279 419
116618:34:00.
116718:34:00# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/redis/backfill_project_pipeline_status_ttl_spec.rb. Expected to take 15.59 seconds.
116818:34:00Gitlab::BackgroundMigration::Redis::BackfillProjectPipelineStatusTtl
116918:34:01 #redis
117018:34:07 is expected to eq "#<Redis client v5.3.0 for 172.26.0.3:7001 172.26.0.3:7002 172.26.0.3:7003>"
117118:34:07# [RSpecRunTime] RSpec elapsed time: 45 minutes 37.5 seconds. Current RSS: ~1272M. Threads: 1. load average: 1.04 1.06 1.08 1/279 420
117218:34:07.
117318:34:07 #scan_match_pattern
117418:34:11 finds all the required keys only
117518:34:11# [RSpecRunTime] RSpec elapsed time: 45 minutes 42.21 seconds. Current RSS: ~1259M. Threads: 1. load average: 0.95 1.04 1.07 1/279 421
117618:34:11.
117718:34:11 #perform
117818:34:16 sets a ttl on given keys
117918:34:16# [RSpecRunTime] RSpec elapsed time: 45 minutes 46.97 seconds. Current RSS: ~1253M. Threads: 1. load average: 0.96 1.04 1.07 1/279 422
118018:34:16.
118118:34:27# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/redis/backfill_project_pipeline_status_ttl_spec.rb. It took 26.61 seconds. Expected to take 15.59 seconds.
118218:34:27# [RSpecRunTime] RSpec elapsed time: 45 minutes 57.89 seconds. Current RSS: ~1237M. Threads: 1. load average: 0.96 1.04 1.07 1/279 423
118318:34:27.
118418:34:27# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb. Expected to take 11.79 seconds.
118518:34:27Gitlab::BackgroundMigration::DeduplicateLfsObjectsProjects
118618:34:28 #perform
118718:34:28 with duplicates
118818:34:33 deduplicates lfs_objects_projects by lfs_object_id, repository_type and project_id
118918:34:33# [RSpecRunTime] RSpec elapsed time: 46 minutes 3.7 seconds. Current RSS: ~1256M. Threads: 1. load average: 0.97 1.04 1.07 1/279 424
119018:34:33.
119118:34:33 without duplicates
119218:34:38 does not delete any records
119318:34:38# [RSpecRunTime] RSpec elapsed time: 46 minutes 8.57 seconds. Current RSS: ~1254M. Threads: 1. load average: 1.05 1.05 1.07 1/279 425
119418:34:38.
119518:34:49# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb. It took 21.81 seconds. Expected to take 11.79 seconds.
119618:34:49# [RSpecRunTime] RSpec elapsed time: 46 minutes 19.76 seconds. Current RSS: ~1243M. Threads: 1. load average: 1.04 1.05 1.07 1/279 426
119718:34:49.
119818:34:49# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/backfill_deploy_tokens_sharding_key_spec.rb. Expected to take 7.95 seconds.
119918:34:49Gitlab::BackgroundMigration::BackfillDeployTokensShardingKey
120018:34:56 backfills the missing project_id or namespace_id
120118:35:09# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/backfill_deploy_tokens_sharding_key_spec.rb. It took 20.44 seconds. Expected to take 7.95 seconds.
120218:35:09# [RSpecRunTime] RSpec elapsed time: 46 minutes 40.25 seconds. Current RSS: ~1245M. Threads: 1. load average: 1.08 1.06 1.08 1/279 427
120318:35:09.
120418:35:09# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb. Expected to take 6.9 seconds.
120518:35:09Gitlab::BackgroundMigration::PopulateTopicsSlugColumn
120618:35:10 #perform
120718:35:15 populates topics slug column
120818:35:15# [RSpecRunTime] RSpec elapsed time: 46 minutes 45.67 seconds. Current RSS: ~1265M. Threads: 1. load average: 1.07 1.06 1.08 1/279 428
120918:35:15.
121018:35:26# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb. It took 16.59 seconds. Expected to take 6.9 seconds.
121118:35:26# [RSpecRunTime] RSpec elapsed time: 46 minutes 56.89 seconds. Current RSS: ~1249M. Threads: 1. load average: 1.06 1.06 1.08 1/279 429
121218:35:26.
121318:35:26# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/disable_legacy_open_source_license_for_inactive_public_projects_spec.rb. Expected to take 6.42 seconds.
121418:35:26Gitlab::BackgroundMigration::DisableLegacyOpenSourceLicenseForInactivePublicProjects
121518:35:31 sets `legacy_open_source_license_available` attribute to false for inactive, public projects
121618:35:42# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/disable_legacy_open_source_license_for_inactive_public_projects_spec.rb. It took 16.08 seconds. Expected to take 6.42 seconds.
121718:35:42# [RSpecRunTime] RSpec elapsed time: 47 minutes 13.02 seconds. Current RSS: ~1254M. Threads: 1. load average: 1.03 1.05 1.07 1/279 430
121818:35:42.
121918:35:42# [RSpecRunTime] Starting example group spec/lib/gitlab/background_migration/mailers/unconfirm_mailer_spec.rb. Expected to take 5.77 seconds.
122018:35:42Gitlab::BackgroundMigration::Mailers::UnconfirmMailer
122118:35:48 contains abuse report url
122218:35:58# [RSpecRunTime] Finishing example group spec/lib/gitlab/background_migration/mailers/unconfirm_mailer_spec.rb. It took 16.08 seconds. Expected to take 5.77 seconds.
122318:35:58# [RSpecRunTime] RSpec elapsed time: 47 minutes 29.15 seconds. Current RSS: ~1250M. Threads: 1. load average: 1.03 1.05 1.07 1/280 431
122418:35:58.
122518:36:48auto_explain log contains 1154 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-7-10.341.main.ndjson.gz
122618:36:48took 50.15152182
122718:37:36auto_explain log contains 1154 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-background-migration-pg14-7-10.341.ci.ndjson.gz
122818:37:36took 47.788937031
122918:37:36[TEST PROF INFO] EventProf results for sql.active_record
123018:37:36Total time: 26:50.207 of 47:16.732 (56.76%)
123118:37:36Total events: 1197466
123218:37:36Top 5 slowest suites (by time):
123318:37:36Gitlab::Backg...ntRepositories (./spec/lib/gitlab/background_migration/backfill_design_management_repositories_spec.rb:9) – 02:40.533 (116533 / 2) of 04:38.644 (57.61%)
123418:37:36Gitlab::Backg...esWithDefaults (./spec/lib/gitlab/background_migration/backfill_user_preferences_with_defaults_spec.rb:5) – 02:37.988 (113884 / 1) of 04:11.106 (62.92%)
123518:37:36Gitlab::Backg...pelineMetadata (./spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_metadata_spec.rb:5) – 02:18.272 (96168 / 2) of 03:56.882 (58.37%)
123618:37:36Gitlab::Backg...ineOnProjectId (./spec/lib/gitlab/background_migration/backfill_or_drop_ci_pipeline_on_project_id_spec.rb:5) – 02:06.451 (97287 / 4) of 03:48.604 (55.31%)
123718:37:36Gitlab::Backg...RulesProjectId (./spec/lib/gitlab/background_migration/backfill_approval_merge_request_rules_project_id_spec.rb:5) – 01:42.863 (77082 / 2) of 02:51.250 (60.07%)
123818:37:36Knapsack report was generated. Preview:
123918:37:36{
124018:37:36 "spec/lib/gitlab/background_migration/backfill_design_management_repositories_spec.rb": 148.99586214199917,
124118:37:36 "spec/lib/gitlab/background_migration/backfill_user_preferences_with_defaults_spec.rb": 118.54884316100015,
124218:37:36 "spec/lib/gitlab/background_migration/backfill_or_drop_ci_pipeline_on_project_id_spec.rb": 122.34053212600065,
124318:37:36 "spec/lib/gitlab/background_migration/backfill_partition_id_ci_pipeline_metadata_spec.rb": 124.55248947200016,
124418:37:36 "spec/lib/gitlab/background_migration/backfill_approval_merge_request_rules_project_id_spec.rb": 85.3749822209993,
124518:37:36 "spec/lib/gitlab/background_migration/backfill_ci_build_needs_project_id_spec.rb": 81.45767417099978,
124618:37:36 "spec/lib/gitlab/background_migration/backfill_vulnerability_merge_request_links_project_id_spec.rb": 69.49585798399949,
124718:37:36 "spec/lib/gitlab/background_migration/backfill_issue_links_namespace_id_spec.rb": 68.39013152499956,
124818:37:36 "spec/lib/gitlab/background_migration/backfill_audit_events_streaming_event_type_filters_group_id_spec.rb": 70.93815374500082,
124918:37:36 "spec/lib/gitlab/background_migration/backfill_agent_activity_events_agent_project_id_spec.rb": 69.40874360499947,
125018:37:36 "spec/lib/gitlab/background_migration/backfill_ml_candidate_metadata_project_id_spec.rb": 55.01418380799987,
125118:37:36 "spec/lib/gitlab/background_migration/backfill_packages_debian_project_architectures_project_id_spec.rb": 55.21733229600068,
125218:37:36 "spec/lib/gitlab/background_migration/backfill_protected_tag_create_access_levels_project_id_spec.rb": 55.731647613999485,
125318:37:36 "spec/lib/gitlab/background_migration/backfill_evidences_project_id_spec.rb": 47.87583297099991,
125418:37:36 "spec/lib/gitlab/background_migration/backfill_sharding_key_id_on_ci_runners_spec.rb": 40.68888072300069,
125518:37:36 "spec/lib/gitlab/background_migration/resync_basic_epic_fields_to_work_item_spec.rb": 45.20312984500015,
125618:37:36 "spec/lib/gitlab/background_migration/recalculate_dora_deployment_frequency_score_spec.rb": 30.83662185600042,
125718:37:36 "spec/lib/gitlab/background_migration/backfill_dast_profiles_pipelines_project_id_spec.rb": 22.51404432599975,
125818:37:36 "spec/lib/gitlab/background_migration/backfill_packages_nuget_metadata_project_id_spec.rb": 22.751463823999984,
125918:37:36 "spec/lib/gitlab/background_migration/migrate_os_sbom_occurrences_to_components_without_prefix_spec.rb": 16.08168575400032,
126018:37:36 "spec/lib/gitlab/background_migration/redis/backfill_project_pipeline_status_ttl_spec.rb": 15.74818884599972,
126118:37:36 "spec/lib/gitlab/background_migration/deduplicate_lfs_objects_projects_spec.rb": 10.694702623000012,
126218:37:36 "spec/lib/gitlab/background_migration/backfill_deploy_tokens_sharding_key_spec.rb": 7.1380484940000315,
126318:37:36 "spec/lib/gitlab/background_migration/populate_topics_slug_column_spec.rb": 5.442907067000306,
126418:37:36 "spec/lib/gitlab/background_migration/disable_legacy_open_source_license_for_inactive_public_projects_spec.rb": 5.503887582000061,
126518:37:36 "spec/lib/gitlab/background_migration/mailers/unconfirm_mailer_spec.rb": 5.457968235999942
126618:37:36}
126718:37:36Knapsack global time execution for tests: 23m 21s
126818:37:36Finished in 49 minutes 7 seconds (files took 1 minute 11.6 seconds to load)
126918:37:3650 examples, 0 failures
127018:37:36Randomized with seed 9267
127118:37:41[TEST PROF INFO] Time spent in factories: 00:00.499 (0.02% of total time)
127218:37:42RSpec exited with 0.
127318:37:42No examples to retry, congrats!
1274 18:37:42 Running after_script 00:21
127518:37:43Running after script...
127618:37:43$ source scripts/utils.sh
127718:37:43$ log_disk_usage
127818:37:43*******************************************************
127918:37:43This runner currently has 75G free disk space.
128018:37:43*******************************************************
1281 18:37:43 Disk usage detail 00:12
133718:37:55$ bundle exec gem list gitlab_quality-test_tooling
133818:37:57gitlab_quality-test_tooling (2.1.0)
133918:37:57$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command
1340 18:37:57 Report test failures 00:00
134218:37:57$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command
1343 18:37:57 Report test flakiness 00:00
134518:37:57$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command
1346 18:37:57 Report test slowness 00:00
134818:37:57$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command
1349 18:37:57 Report test slowness in MR note 00:03
135218:38:00$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command
1353 18:38:00 Report test files close to timing out 00:02
135618:38:02$ tooling/bin/push_job_metrics || true
135718:38:03[job-metrics] Pushing job metrics file for the CI/CD job.
135818:38:03[job-metrics] Pushed 4 CI job metric entries to InfluxDB.
1359 18:38:03 Saving cache for successful job 00:01
136018:38:04Not uploading cache ruby-gems-debian-bookworm-ruby-3.2.5-gemfile-Gemfile-20 due to policy
1361 18:38:04 Uploading artifacts for successful job 00:06
136218:38:06Uploading artifacts...
136318:38:06auto_explain/: found 3 matching artifact files and directories
136418:38:06coverage/: found 5 matching artifact files and directories
136518:38:06crystalball/: found 2 matching artifact files and directories
136618:38:06WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
136718:38:06knapsack/: found 4 matching artifact files and directories
136818:38:06rspec/: found 16 matching artifact files and directories
136918:38:06WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab)
137018:38:06log/*.log: found 13 matching artifact files and directories
137118:38:06WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8298976152/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com
137218:38:06WARNING: Retrying... context=artifacts-uploader error=request redirected
137318:38:08Uploading artifacts as "archive" to coordinator... 201 Created id=8298976152 responseStatus=201 Created token=glcbt-66
137418:38:09Uploading artifacts...
137518:38:09rspec/rspec-*.xml: found 1 matching artifact files and directories
137618:38:09WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/8298976152/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com
137718:38:09WARNING: Retrying... context=artifacts-uploader error=request redirected
137818:38:10Uploading artifacts as "junit" to coordinator... 201 Created id=8298976152 responseStatus=201 Created token=glcbt-66
1379 18:38:10 Cleaning up project directory and file based variables 00:01
138018:38:21Job succeeded