rspec migration pg14 13/15
Passed Started
by
@ahegyi
Adam Hegyi
1Running with gitlab-runner 17.0.0~pre.88.g761ae5dd (761ae5dd)2 on green-1.shared-gitlab-org.runners-manager.gitlab.com/default Mf8beF5G, system ID: s_673deea48c2a3 feature flags: FF_NETWORK_PER_BUILD:true4Resolving secrets6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...7Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...8Authenticating with credentials from job payload (GitLab Registry)9Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:postgres-14-pgvector-0.4.1 ...10Using 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 ...11WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.12WARNING: Service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 is already created. Ignoring.13Starting service registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...14Authenticating with credentials from job payload (GitLab Registry)15Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images:redis-cluster-6.2.12 ...16Using 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 ...17Starting service redis:6.2-alpine ...18Pulling docker image redis:6.2-alpine ...19Using docker image sha256:0fc0b666ef9eb249e61edd4e2ec434a328e98117079727c5e7cf6dc9a00d7a94 for redis:6.2-alpine with digest redis@sha256:e31ca60b18f7e9b78b573d156702471d4eda038803c0b8e6f01559f350031e93 ...20Waiting for services to be up and running (timeout 30 seconds)...21Authenticating with credentials from job payload (GitLab Registry)22Pulling docker image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-123-yarn-1.22-graphicsmagick-1.3.36 ...23Using docker image sha256:b7f013d6c497fdb34b7c3895a9ae4f7df0abc05338ad5cb26e19d48ef6d6c4af for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:a86b6a5e81e97ddf8d792c86400f1d3960104b1dcecc6ef34d7fc5b154e65a4b ...25Running on runner-mf8bef5g-project-278964-concurrent-0 via runner-mf8bef5g-shared-gitlab-org-1716556349-36312bea...27Skipping Git repository setup28Skipping Git checkout29Skipping Git submodules setup31Checking cache for ruby-gems-debian-bookworm-ruby-3.1-17...32Downloading cache from https://storage.googleapis.com/gitlab-com-runners-cache/project/278964/ruby-gems-debian-bookworm-ruby-3.1-17 33Successfully extracted cache35Downloading artifacts for clone-gitlab-repo (6934017441)...36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6934017441 responseStatus=200 OK token=glcbt-6537WARNING: Part of .git directory is on the list of files to extract 38WARNING: This may introduce unexpected problems 39Downloading artifacts for compile-test-assets (6934017525)...40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6934017525 responseStatus=200 OK token=glcbt-6541Downloading artifacts for retrieve-tests-metadata (6934017590)...42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6934017590 responseStatus=200 OK token=glcbt-6543Downloading artifacts for setup-test-env (6934017562)...44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6934017562 responseStatus=200 OK token=glcbt-65 45 Executing "step_script" stage of the job script 46Using docker image sha256:b7f013d6c497fdb34b7c3895a9ae4f7df0abc05338ad5cb26e19d48ef6d6c4af for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14:rubygems-3.4-git-2.36-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.1.patched-golang-1.21-rust-1.73-node-20.12-postgresql-14@sha256:a86b6a5e81e97ddf8d792c86400f1d3960104b1dcecc6ef34d7fc5b154e65a4b ...47$ echo $FOSS_ONLY48$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb49$ export GOPATH=$CI_PROJECT_DIR/.go50$ mkdir -p $GOPATH51$ source scripts/utils.sh52$ source scripts/prepare_build.sh753Using decomposed database config (config/database.yml.decomposed-postgresql)754Geo DB won't be set up.755Embedding DB won't be set up.767$ source ./scripts/rspec_helpers.sh768$ run_timed_command "gem install knapsack --no-document"769$ gem install knapsack --no-document770Successfully installed knapsack-4.0.07711 gem installed772==> 'gem install knapsack --no-document' succeeded in 2 seconds.773$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"778$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"779$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"780$ tooling/bin/create_job_metrics_file || true781[job-metrics] Creating the job metrics file for the CI/CD job.782$ rspec_section rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"784[15:42:55] Starting rspec_parallelized_job785RETRY_FAILED_TESTS_IN_NEW_PROCESS: true786KNAPSACK_GENERATE_REPORT: true787FLAKY_RSPEC_GENERATE_REPORT: true788KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb789KNAPSACK_LOG_LEVEL: debug790KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_13_15_278964_report.json791FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json792FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_13_15_278964_report.json793NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_13_15_278964_report.json794RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6934018097.txt795CRYSTALBALL: 796RSPEC_TESTS_MAPPING_ENABLED: 797RSPEC_TESTS_FILTER_FILE: 798Shell set options (set -o) enabled:799braceexpand on800hashall on801interactive-comments on802pipefail on803Knapsack report generator started!804warning: parser/current is loading parser/ruby31, which recognizes 3.1.4-compliant syntax, but you are running 3.1.5.806Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}807# [RSpecRunTime] Starting RSpec timer...808[TEST PROF INFO] EventProf enabled (sql.active_record)809unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.810unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.811Test environment set up in 2.792083085 seconds812# [RSpecRunTime] Starting example group spec/migrations/20230817111938_swap_events_target_id_to_bigint_for_self_hosts_spec.rb. Expected to take 2 minutes 52.36 seconds.813SwapEventsTargetIdToBigintForSelfHosts814 #up815 when GitLab.com, dev, or test816 behaves like column `target_id_convert_to_bigint` is already dropped817 does not swap the columns818 when self-managed instance with the `target_id_convert_to_bigint` column already dropped819 behaves like column `target_id_convert_to_bigint` is already dropped820 does not swap the columns821# [RSpecRunTime] RSpec elapsed time: 2 minutes 11.86 seconds. Current RSS: ~1266M. load average: 1.03 0.94 0.94 1/267 403822 when self-managed instance columns already swapped823 does not swaps the columns824# [RSpecRunTime] RSpec elapsed time: 2 minutes 31.45 seconds. Current RSS: ~1228M. load average: 1.03 0.94 0.94 1/267 404825 when self-managed instance826 swaps the columns827# [RSpecRunTime] RSpec elapsed time: 3 minutes 7.03 seconds. Current RSS: ~1249M. load average: 1.01 0.95 0.95 1/267 405828INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree829INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"830INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows831INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"832INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows833INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"834INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows835INFO: analyzing "public.p_ci_job_annotations" inheritance tree836INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"837INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows838INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"839INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows840INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"841INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows842INFO: analyzing "public.p_ci_builds_metadata" inheritance tree843INFO: analyzing "public.ci_builds_metadata"844INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows845INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"846INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows847INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"848INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows849INFO: analyzing "public.p_ci_builds" inheritance tree850INFO: analyzing "public.ci_builds"851INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows852INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"853INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows854INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"855INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows856I, [2024-05-24T15:48:04.024200 #340] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes857I, [2024-05-24T15:48:22.824946 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes858INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree859INFO: analyzing "public.ci_pipeline_variables"860INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows861I, [2024-05-24T15:48:24.387228 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes862I, [2024-05-24T15:48:24.388441 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}863I, [2024-05-24T15:48:24.389277 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}864INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree865INFO: analyzing "public.ci_pipeline_variables"866INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows867INFO: analyzing "public.p_ci_job_artifacts" inheritance tree868INFO: analyzing "public.ci_job_artifacts"869INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows870I, [2024-05-24T15:48:30.160257 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes871I, [2024-05-24T15:48:30.161516 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}872I, [2024-05-24T15:48:30.162432 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}873INFO: analyzing "public.p_ci_stages" inheritance tree874INFO: analyzing "public.ci_stages"875INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows876I, [2024-05-24T15:48:33.573562 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes877I, [2024-05-24T15:48:33.574942 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}878I, [2024-05-24T15:48:33.575917 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}879I, [2024-05-24T15:48:34.692281 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes880I, [2024-05-24T15:48:40.831722 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes881# [RSpecRunTime] Finishing example group spec/migrations/20230817111938_swap_events_target_id_to_bigint_for_self_hosts_spec.rb. It took 4 minutes 3.23 seconds. Expected to take 2 minutes 52.36 seconds.882# [RSpecRunTime] RSpec elapsed time: 4 minutes 9.27 seconds. Current RSS: ~1160M. load average: 0.92 0.93 0.94 1/268 407883# [RSpecRunTime] Starting example group spec/migrations/20230809210550_swap_issue_user_mentions_note_id_to_bigint_for_self_managed_spec.rb. Expected to take 2 minutes 43.13 seconds.884SwapIssueUserMentionsNoteIdToBigintForSelfManaged885 #up886 when GitLab.com, dev, or test887 behaves like column `note_id_convert_to_bigint` is already dropped888 does not swaps the columns889# [RSpecRunTime] RSpec elapsed time: 5 minutes 36.99 seconds. Current RSS: ~1218M. load average: 1.02 0.98 0.96 1/269 408890 when self-managed instance with the `note_id_convert_to_bigint` column already dropped891 behaves like column `note_id_convert_to_bigint` is already dropped892 does not swaps the columns893# [RSpecRunTime] RSpec elapsed time: 6 minutes 13.12 seconds. Current RSS: ~1213M. load average: 1.01 0.98 0.96 1/269 409894 when self-managed instance columns already swapped895 does not swaps the columns896# [RSpecRunTime] RSpec elapsed time: 6 minutes 33.86 seconds. Current RSS: ~1224M. load average: 1.09 1.00 0.97 1/269 410897 when self-managed instance898 swaps the columns899# [RSpecRunTime] RSpec elapsed time: 7 minutes 10.33 seconds. Current RSS: ~1230M. load average: 1.05 1.00 0.97 1/269 411900INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree901INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"902INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows903INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"904INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows905INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"906INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows907INFO: analyzing "public.p_ci_job_annotations" inheritance tree908INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"909INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows910INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"911INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows912INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"913INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows914INFO: analyzing "public.p_ci_builds_metadata" inheritance tree915INFO: analyzing "public.ci_builds_metadata"916INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows917INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"918INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows919INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"920INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows921INFO: analyzing "public.p_ci_builds" inheritance tree922INFO: analyzing "public.ci_builds"923INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows924INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"925INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows926INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"927INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows928I, [2024-05-24T15:52:07.420993 #340] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes929I, [2024-05-24T15:52:26.685209 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes930INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree931INFO: analyzing "public.ci_pipeline_variables"932INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows933I, [2024-05-24T15:52:28.255332 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes934I, [2024-05-24T15:52:28.256465 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}935I, [2024-05-24T15:52:28.257290 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}936INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree937INFO: analyzing "public.ci_pipeline_variables"938INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows939INFO: analyzing "public.p_ci_job_artifacts" inheritance tree940INFO: analyzing "public.ci_job_artifacts"941INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows942I, [2024-05-24T15:52:34.142301 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes943I, [2024-05-24T15:52:34.143478 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}944I, [2024-05-24T15:52:34.144346 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}945INFO: analyzing "public.p_ci_stages" inheritance tree946INFO: analyzing "public.ci_stages"947INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows948I, [2024-05-24T15:52:37.475688 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes949I, [2024-05-24T15:52:37.476872 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}950I, [2024-05-24T15:52:37.477682 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}951I, [2024-05-24T15:52:38.661049 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes952I, [2024-05-24T15:52:44.859807 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes953# [RSpecRunTime] Finishing example group spec/migrations/20230809210550_swap_issue_user_mentions_note_id_to_bigint_for_self_managed_spec.rb. It took 4 minutes 4.02 seconds. Expected to take 2 minutes 43.13 seconds.954# [RSpecRunTime] RSpec elapsed time: 8 minutes 13.34 seconds. Current RSS: ~1163M. load average: 1.08 1.02 0.98 1/269 412955# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20231004091113_swap_columns_for_ci_sources_pipelines_pipeline_id_bigint_spec.rb. Expected to take 1 minute 46.41 seconds.956SwapColumnsForCiSourcesPipelinesPipelineIdBigint957 when indexes are missing958 behaves like swap conversion columns959main: == [advisory_lock_connection] object_id: 83897280, pg_backend_pid: 211960main: == 20231004091113 SwapColumnsForCiSourcesPipelinesPipelineIdBigint: migrating =961main: -- index_exists?(:ci_sources_pipelines, :pipeline_id, {:name=>:index_ci_sources_pipelines_on_pipeline_id})962main: -> 0.0117s963main: -- view_exists?(:postgres_partitions)964main: -> 0.0020s965main: -- index_exists?(:ci_sources_pipelines, :pipeline_id, {:name=>:index_ci_sources_pipelines_on_pipeline_id, :algorithm=>:concurrently})966main: -> 0.0110s967main: -- add_index(:ci_sources_pipelines, :pipeline_id, {:name=>:index_ci_sources_pipelines_on_pipeline_id, :algorithm=>:concurrently})968main: -> 0.0018s969main: -- index_exists?(:ci_sources_pipelines, :source_pipeline_id, {:name=>:index_ci_sources_pipelines_on_source_pipeline_id})970main: -> 0.0123s971main: -- view_exists?(:postgres_partitions)972main: -> 0.0019s973main: -- index_exists?(:ci_sources_pipelines, :source_pipeline_id, {:name=>:index_ci_sources_pipelines_on_source_pipeline_id, :algorithm=>:concurrently})974main: -> 0.0134s975main: -- add_index(:ci_sources_pipelines, :source_pipeline_id, {:name=>:index_ci_sources_pipelines_on_source_pipeline_id, :algorithm=>:concurrently})976main: -> 0.0019s977main: -- execute("LOCK TABLE ci_pipelines, ci_sources_pipelines IN ACCESS EXCLUSIVE MODE")978main: -> 0.0011s979main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN pipeline_id TO temp_pipeline_id")980main: -> 0.0011s981main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN pipeline_id_convert_to_bigint TO pipeline_id")982main: -> 0.0010s983main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN temp_pipeline_id TO pipeline_id_convert_to_bigint")984main: -> 0.0010s985main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN source_pipeline_id TO temp_source_pipeline_id")986main: -> 0.0009s987main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN source_pipeline_id_convert_to_bigint TO source_pipeline_id")988main: -> 0.0012s989main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN temp_source_pipeline_id TO source_pipeline_id_convert_to_bigint")990main: -> 0.0010s991main: -- quote_column_name(:trigger_68d7b6653c7d)992main: -> 0.0001s993main: -- execute("ALTER FUNCTION \"trigger_68d7b6653c7d\" RESET ALL")994main: -> 0.0010s995main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_e1bad85861 TO temp_fk_e1bad85861")996main: -> 0.0010s997main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_c1b5dc6b6f TO fk_e1bad85861")998main: -> 0.0012s999main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT temp_fk_e1bad85861 TO fk_c1b5dc6b6f")1000main: -> 0.0009s1001main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_d4e29af7d7 TO temp_fk_d4e29af7d7")1002main: -> 0.0010s1003main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_1df371767f TO fk_d4e29af7d7")1004main: -> 0.0010s1005main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT temp_fk_d4e29af7d7 TO fk_1df371767f")1006main: -> 0.0012s1007main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_pipeline_id RENAME TO temp_index_ci_sources_pipelines_on_pipeline_id")1008main: -> 0.0009s1009main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_pipeline_id_bigint RENAME TO index_ci_sources_pipelines_on_pipeline_id")1010main: -> 0.0010s1011main: -- execute("ALTER INDEX temp_index_ci_sources_pipelines_on_pipeline_id RENAME TO index_ci_sources_pipelines_on_pipeline_id_bigint")1012main: -> 0.0009s1013main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_source_pipeline_id RENAME TO temp_index_ci_sources_pipelines_on_source_pipeline_id")1014main: -> 0.0011s1015main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_source_pipeline_id_bigint RENAME TO index_ci_sources_pipelines_on_source_pipeline_id")1016main: -> 0.0010s1017main: -- execute("ALTER INDEX temp_index_ci_sources_pipelines_on_source_pipeline_id RENAME TO index_ci_sources_pipelines_on_source_pipeline_id_bigint")1018main: -> 0.0010s1019main: == 20231004091113 SwapColumnsForCiSourcesPipelinesPipelineIdBigint: migrated (0.1277s) 1020main: == [advisory_lock_connection] object_id: 83897280, pg_backend_pid: 2111021 correctly swaps conversion columns1022# [RSpecRunTime] RSpec elapsed time: 9 minutes 31.36 seconds. Current RSS: ~1226M. load average: 1.07 1.03 1.00 1/270 4131023 behaves like swap conversion columns1024main: == [advisory_lock_connection] object_id: 84778120, pg_backend_pid: 2161025main: == 20231004091113 SwapColumnsForCiSourcesPipelinesPipelineIdBigint: migrating =1026main: -- index_exists?(:ci_sources_pipelines, :pipeline_id, {:name=>:index_ci_sources_pipelines_on_pipeline_id})1027main: -> 0.0178s1028main: -- index_exists?(:ci_sources_pipelines, :source_pipeline_id, {:name=>:index_ci_sources_pipelines_on_source_pipeline_id})1029main: -> 0.0159s1030main: -- execute("LOCK TABLE ci_pipelines, ci_sources_pipelines IN ACCESS EXCLUSIVE MODE")1031main: -> 0.0013s1032main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN pipeline_id TO temp_pipeline_id")1033main: -> 0.0017s1034main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN pipeline_id_convert_to_bigint TO pipeline_id")1035main: -> 0.0011s1036main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN temp_pipeline_id TO pipeline_id_convert_to_bigint")1037main: -> 0.0010s1038main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN source_pipeline_id TO temp_source_pipeline_id")1039main: -> 0.0010s1040main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN source_pipeline_id_convert_to_bigint TO source_pipeline_id")1041main: -> 0.0014s1042main: -- execute("ALTER TABLE ci_sources_pipelines RENAME COLUMN temp_source_pipeline_id TO source_pipeline_id_convert_to_bigint")1043main: -> 0.0010s1044main: -- quote_column_name(:trigger_68d7b6653c7d)1045main: -> 0.0000s1046main: -- execute("ALTER FUNCTION \"trigger_68d7b6653c7d\" RESET ALL")1047main: -> 0.0010s1048main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_e1bad85861 TO temp_fk_e1bad85861")1049main: -> 0.0011s1050main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_c1b5dc6b6f TO fk_e1bad85861")1051main: -> 0.0014s1052main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT temp_fk_e1bad85861 TO fk_c1b5dc6b6f")1053main: -> 0.0010s1054main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_d4e29af7d7 TO temp_fk_d4e29af7d7")1055main: -> 0.0010s1056main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT fk_1df371767f TO fk_d4e29af7d7")1057main: -> 0.0011s1058main: -- execute("ALTER TABLE ci_sources_pipelines RENAME CONSTRAINT temp_fk_d4e29af7d7 TO fk_1df371767f")1059main: -> 0.0010s1060main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_pipeline_id RENAME TO temp_index_ci_sources_pipelines_on_pipeline_id")1061main: -> 0.0014s1062main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_pipeline_id_bigint RENAME TO index_ci_sources_pipelines_on_pipeline_id")1063main: -> 0.0010s1064main: -- execute("ALTER INDEX temp_index_ci_sources_pipelines_on_pipeline_id RENAME TO index_ci_sources_pipelines_on_pipeline_id_bigint")1065main: -> 0.0010s1066main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_source_pipeline_id RENAME TO temp_index_ci_sources_pipelines_on_source_pipeline_id")1067main: -> 0.0010s1068main: -- execute("ALTER INDEX index_ci_sources_pipelines_on_source_pipeline_id_bigint RENAME TO index_ci_sources_pipelines_on_source_pipeline_id")1069main: -> 0.0014s1070main: -- execute("ALTER INDEX temp_index_ci_sources_pipelines_on_source_pipeline_id RENAME TO index_ci_sources_pipelines_on_source_pipeline_id_bigint")1071main: -> 0.0012s1072main: == 20231004091113 SwapColumnsForCiSourcesPipelinesPipelineIdBigint: migrated (0.0778s) 1073main: == [advisory_lock_connection] object_id: 84778120, pg_backend_pid: 2161074 correctly swaps conversion columns1075# [RSpecRunTime] RSpec elapsed time: 10 minutes 8.03 seconds. Current RSS: ~1217M. load average: 1.03 1.02 1.00 1/270 4141076I, [2024-05-24T15:55:20.001368 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1077INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1078INFO: analyzing "public.ci_pipeline_variables"1079INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1080I, [2024-05-24T15:55:21.833818 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1081I, [2024-05-24T15:55:21.835211 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1082I, [2024-05-24T15:55:21.836191 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1083INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1084INFO: analyzing "public.ci_pipeline_variables"1085INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1086INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1087INFO: analyzing "public.ci_job_artifacts"1088INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1089I, [2024-05-24T15:55:28.753124 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1090I, [2024-05-24T15:55:28.754841 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1091I, [2024-05-24T15:55:28.756007 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1092INFO: analyzing "public.p_ci_stages" inheritance tree1093INFO: analyzing "public.ci_stages"1094INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1095I, [2024-05-24T15:55:32.558471 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1096I, [2024-05-24T15:55:32.559982 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1097I, [2024-05-24T15:55:32.560933 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1098I, [2024-05-24T15:55:34.152367 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1099I, [2024-05-24T15:55:41.551150 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1100# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20231004091113_swap_columns_for_ci_sources_pipelines_pipeline_id_bigint_spec.rb. It took 2 minutes 59.22 seconds. Expected to take 1 minute 46.41 seconds.1101# [RSpecRunTime] RSpec elapsed time: 11 minutes 12.61 seconds. Current RSS: ~1151M. load average: 0.95 1.00 1.00 1/270 4151102# [RSpecRunTime] Starting example group spec/migrations/20230816152540_ensure_dum_note_id_bigint_backfill_is_finished_for_self_managed_spec.rb. Expected to take 1 minute 31.81 seconds.1103EnsureDumNoteIdBigintBackfillIsFinishedForSelfManaged1104 #up1105main: == [advisory_lock_connection] object_id: 116480200, pg_backend_pid: 2341106main: == 20230816152540 EnsureDumNoteIdBigintBackfillIsFinishedForSelfManaged: migrating 1107main: == 20230816152540 EnsureDumNoteIdBigintBackfillIsFinishedForSelfManaged: migrated (0.0112s) 1108main: == [advisory_lock_connection] object_id: 116480200, pg_backend_pid: 2341109 ensures the migration is completed for self-managed instances1110main: == [advisory_lock_connection] object_id: 117041900, pg_backend_pid: 2371111main: == 20230816152540 EnsureDumNoteIdBigintBackfillIsFinishedForSelfManaged: migrating 1112main: == 20230816152540 EnsureDumNoteIdBigintBackfillIsFinishedForSelfManaged: migrated (0.0112s) 1113main: == [advisory_lock_connection] object_id: 117041900, pg_backend_pid: 2371114 skips the check for GitLab.com, dev, or test1115# [RSpecRunTime] RSpec elapsed time: 12 minutes 57.1 seconds. Current RSS: ~1201M. load average: 1.06 1.04 1.01 1/270 4161116INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1117INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1118INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1119INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1120INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1121INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"1122INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1123INFO: analyzing "public.p_ci_job_annotations" inheritance tree1124INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1125INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1126INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"1127INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1128INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"1129INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1130INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1131INFO: analyzing "public.ci_builds_metadata"1132INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1133INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1134INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1135INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"1136INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1137INFO: analyzing "public.p_ci_builds" inheritance tree1138INFO: analyzing "public.ci_builds"1139INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1140INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1141INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1142INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"1143INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1144I, [2024-05-24T15:57:54.051697 #340] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1145I, [2024-05-24T15:58:15.146793 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1146INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1147INFO: analyzing "public.ci_pipeline_variables"1148INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1149I, [2024-05-24T15:58:16.862761 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1150I, [2024-05-24T15:58:16.864070 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1151I, [2024-05-24T15:58:16.865282 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1152INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1153INFO: analyzing "public.ci_pipeline_variables"1154INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1155INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1156INFO: analyzing "public.ci_job_artifacts"1157INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1158I, [2024-05-24T15:58:23.095317 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1159I, [2024-05-24T15:58:23.097312 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1160I, [2024-05-24T15:58:23.098234 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1161INFO: analyzing "public.p_ci_stages" inheritance tree1162INFO: analyzing "public.ci_stages"1163INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1164I, [2024-05-24T15:58:26.684479 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1165I, [2024-05-24T15:58:26.685757 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1166I, [2024-05-24T15:58:26.686639 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1167I, [2024-05-24T15:58:27.939013 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1168I, [2024-05-24T15:58:35.076971 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1169# [RSpecRunTime] Finishing example group spec/migrations/20230816152540_ensure_dum_note_id_bigint_backfill_is_finished_for_self_managed_spec.rb. It took 2 minutes 52.26 seconds. Expected to take 1 minute 31.81 seconds.1170# [RSpecRunTime] RSpec elapsed time: 14 minutes 4.92 seconds. Current RSS: ~1151M. load average: 0.87 0.99 0.99 1/270 4171171# [RSpecRunTime] Starting example group spec/migrations/20230907155247_queue_backfill_has_merge_request_of_vulnerability_reads_spec.rb. Expected to take 1 minute 27.56 seconds.1172QueueBackfillHasMergeRequestOfVulnerabilityReads1173main: == [advisory_lock_connection] object_id: 149396200, pg_backend_pid: 2531174main: == 20230907155247 QueueBackfillHasMergeRequestOfVulnerabilityReads: migrating =1175main: == 20230907155247 QueueBackfillHasMergeRequestOfVulnerabilityReads: migrated (0.0618s) 1176main: == [advisory_lock_connection] object_id: 149396200, pg_backend_pid: 2531177 schedules a new batched migration1178INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1179INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1180INFO: "ci_runner_machine_builds_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1181INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1182INFO: "ci_runner_machine_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1183INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_102"1184INFO: "ci_runner_machine_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1185INFO: analyzing "public.p_ci_job_annotations" inheritance tree1186INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1187INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1188INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_101"1189INFO: "ci_job_annotations_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1190INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_102"1191INFO: "ci_job_annotations_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1192INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1193INFO: analyzing "public.ci_builds_metadata"1194INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1195INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1196INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1197INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_102"1198INFO: "ci_builds_metadata_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1199INFO: analyzing "public.p_ci_builds" inheritance tree1200INFO: analyzing "public.ci_builds"1201INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1202INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1203INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1204INFO: analyzing "gitlab_partitions_dynamic.ci_builds_102"1205INFO: "ci_builds_102": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1206I, [2024-05-24T16:00:23.859313 #340] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1207I, [2024-05-24T16:00:43.929062 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1208INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1209INFO: analyzing "public.ci_pipeline_variables"1210INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1211I, [2024-05-24T16:00:45.597407 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1212I, [2024-05-24T16:00:45.598550 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1213I, [2024-05-24T16:00:45.599394 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1214INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1215INFO: analyzing "public.ci_pipeline_variables"1216INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1217INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1218INFO: analyzing "public.ci_job_artifacts"1219INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1220I, [2024-05-24T16:00:51.415342 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1221I, [2024-05-24T16:00:51.416706 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1222I, [2024-05-24T16:00:51.417562 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1223INFO: analyzing "public.p_ci_stages" inheritance tree1224INFO: analyzing "public.ci_stages"1225INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1226I, [2024-05-24T16:00:54.772362 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1227I, [2024-05-24T16:00:54.773688 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1228I, [2024-05-24T16:00:54.774474 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1229I, [2024-05-24T16:00:55.900772 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1230I, [2024-05-24T16:01:02.050379 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1231# [RSpecRunTime] Finishing example group spec/migrations/20230907155247_queue_backfill_has_merge_request_of_vulnerability_reads_spec.rb. It took 2 minutes 25.69 seconds. Expected to take 1 minute 27.56 seconds.1232# [RSpecRunTime] RSpec elapsed time: 16 minutes 30.67 seconds. Current RSS: ~1158M. load average: 1.04 1.05 1.00 1/269 4181233# [RSpecRunTime] Starting example group spec/migrations/20231102142554_migrate_zoekt_shards_to_zoekt_nodes_spec.rb. Expected to take 1 minute 17.29 seconds.1234MigrateZoektShardsToZoektNodes1235 #down1236 deletes all zoekt_node records1237# [RSpecRunTime] RSpec elapsed time: 17 minutes 29.83 seconds. Current RSS: ~1189M. load average: 1.27 1.10 1.02 1/269 4191238 #up1239main: == [advisory_lock_connection] object_id: 180759960, pg_backend_pid: 2721240main: == 20231102142554 MigrateZoektShardsToZoektNodes: migrating ===================1241main: == 20231102142554 MigrateZoektShardsToZoektNodes: migrated (0.0112s) ==========1242main: == [advisory_lock_connection] object_id: 180759960, pg_backend_pid: 2721243 migrates zoekt_shard records to zoekt_nodes1244# [RSpecRunTime] RSpec elapsed time: 17 minutes 45.42 seconds. Current RSS: ~1216M. load average: 1.21 1.09 1.02 1/270 4201245I, [2024-05-24T16:02:51.731229 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1246INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1247INFO: analyzing "public.ci_pipeline_variables"1248INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1249I, [2024-05-24T16:02:53.413926 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1250I, [2024-05-24T16:02:53.415454 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1251I, [2024-05-24T16:02:53.416246 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1252INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1253INFO: analyzing "public.ci_pipeline_variables"1254INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1255INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1256INFO: analyzing "public.ci_job_artifacts"1257INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1258I, [2024-05-24T16:02:59.428394 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1259I, [2024-05-24T16:02:59.429565 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1260I, [2024-05-24T16:02:59.430457 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1261INFO: analyzing "public.p_ci_stages" inheritance tree1262INFO: analyzing "public.ci_stages"1263INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1264I, [2024-05-24T16:03:02.846877 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1265I, [2024-05-24T16:03:02.847985 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1266I, [2024-05-24T16:03:02.848776 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1267I, [2024-05-24T16:03:03.968285 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1268I, [2024-05-24T16:03:10.266772 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1269# [RSpecRunTime] Finishing example group spec/migrations/20231102142554_migrate_zoekt_shards_to_zoekt_nodes_spec.rb. It took 2 minutes 8.38 seconds. Expected to take 1 minute 17.29 seconds.1270# [RSpecRunTime] RSpec elapsed time: 18 minutes 39.1 seconds. Current RSS: ~1157M. load average: 1.00 1.06 1.01 1/270 4211271# [RSpecRunTime] Starting example group spec/migrations/20231030071209_queue_backfill_packages_tags_project_id_spec.rb. Expected to take 1 minute 4.25 seconds.1272QueueBackfillPackagesTagsProjectId1273main: == [advisory_lock_connection] object_id: 209856340, pg_backend_pid: 2861274main: == 20231030071209 QueueBackfillPackagesTagsProjectId: migrating ===============1275main: == 20231030071209 QueueBackfillPackagesTagsProjectId: migrated (0.0602s) ======1276main: == [advisory_lock_connection] object_id: 209856340, pg_backend_pid: 2861277 schedules a new batched migration1278I, [2024-05-24T16:04:55.459951 #340] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1279INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1280INFO: analyzing "public.ci_pipeline_variables"1281INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1282I, [2024-05-24T16:04:57.166278 #340] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1283I, [2024-05-24T16:04:57.167438 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1284I, [2024-05-24T16:04:57.168246 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1285INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1286INFO: analyzing "public.ci_pipeline_variables"1287INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1288INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1289INFO: analyzing "public.ci_job_artifacts"1290INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1291I, [2024-05-24T16:05:03.184584 #340] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1292I, [2024-05-24T16:05:03.185687 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1293I, [2024-05-24T16:05:03.186493 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1294INFO: analyzing "public.p_ci_stages" inheritance tree1295INFO: analyzing "public.ci_stages"1296INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1297I, [2024-05-24T16:05:06.588391 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1298I, [2024-05-24T16:05:06.589615 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1299I, [2024-05-24T16:05:06.590474 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1300I, [2024-05-24T16:05:07.732528 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1301I, [2024-05-24T16:05:14.011750 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1302# [RSpecRunTime] Finishing example group spec/migrations/20231030071209_queue_backfill_packages_tags_project_id_spec.rb. It took 2 minutes 3.81 seconds. Expected to take 1 minute 4.25 seconds.1303# [RSpecRunTime] RSpec elapsed time: 20 minutes 42.96 seconds. Current RSS: ~1134M. load average: 1.07 1.06 1.01 1/270 4221304# [RSpecRunTime] Starting example group spec/migrations/20240212172655_self_hosted_sent_notifications_cleanup_spec.rb. Expected to take 51.47 seconds.1305SelfHostedSentNotificationsCleanup1306 #up1307 when is GitLab.com, dev, or test1308 does nothing1309# [RSpecRunTime] RSpec elapsed time: 21 minutes 22.42 seconds. Current RSS: ~1194M. load average: 1.14 1.08 1.02 1/270 4231310 when is a self-host customer with the temporary column already dropped1311 does nothing1312# [RSpecRunTime] RSpec elapsed time: 21 minutes 31.05 seconds. Current RSS: ~1204M. load average: 1.13 1.08 1.02 1/270 4241313 when is a self-host with the temporary columns1314 drops the temporary columns1315# [RSpecRunTime] RSpec elapsed time: 21 minutes 39.69 seconds. Current RSS: ~1191M. load average: 1.11 1.08 1.02 1/270 4251316INFO: analyzing "public.p_ci_stages" inheritance tree1317INFO: analyzing "public.ci_stages"1318INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1319I, [2024-05-24T16:06:33.988748 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1320I, [2024-05-24T16:06:33.990026 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1321I, [2024-05-24T16:06:33.990939 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1322I, [2024-05-24T16:06:35.140733 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1323I, [2024-05-24T16:06:41.434319 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1324# [RSpecRunTime] Finishing example group spec/migrations/20240212172655_self_hosted_sent_notifications_cleanup_spec.rb. It took 1 minute 27.28 seconds. Expected to take 51.47 seconds.1325# [RSpecRunTime] RSpec elapsed time: 22 minutes 10.31 seconds. Current RSS: ~1163M. load average: 1.07 1.07 1.01 2/270 4261326# [RSpecRunTime] Starting example group spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb. Expected to take 36.8 seconds.1327SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines1328 behaves like swap conversion columns1329main: == [advisory_lock_connection] object_id: 255508140, pg_backend_pid: 3191330main: == 20240219040351 SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines: migrating 1331main: -- indexes(:p_ci_builds)1332main: -> 0.0486s1333main: -- execute("LOCK TABLE ci_pipelines, p_ci_builds IN ACCESS EXCLUSIVE MODE")1334main: -> 0.0012s1335main: -- clear_cache!(nil)1336main: -> 0.0001s1337main: -- quote_table_name(:p_ci_builds)1338main: -> 0.0000s1339main: -- quote_column_name(:auto_canceled_by_id_convert_to_bigint)1340main: -> 0.0000s1341main: -- quote_column_name(:temp_name_for_renaming)1342main: -> 0.0000s1343main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"auto_canceled_by_id_convert_to_bigint\" TO \"temp_name_for_renaming\"\n")1344main: -> 0.0016s1345main: -- clear_cache!(nil)1346main: -> 0.0000s1347main: -- quote_table_name(:p_ci_builds)1348main: -> 0.0001s1349main: -- quote_column_name(:auto_canceled_by_id)1350main: -> 0.0000s1351main: -- quote_column_name(:auto_canceled_by_id_convert_to_bigint)1352main: -> 0.0005s1353main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"auto_canceled_by_id\" TO \"auto_canceled_by_id_convert_to_bigint\"\n")1354main: -> 0.0019s1355main: -- clear_cache!(nil)1356main: -> 0.0000s1357main: -- quote_table_name(:p_ci_builds)1358main: -> 0.0000s1359main: -- quote_column_name(:temp_name_for_renaming)1360main: -> 0.0000s1361main: -- quote_column_name(:auto_canceled_by_id)1362main: -> 0.0000s1363main: -- execute("ALTER TABLE \"p_ci_builds\"\n RENAME COLUMN \"temp_name_for_renaming\" TO \"auto_canceled_by_id\"\n")1364main: -> 0.0014s1365main: -- quote_table_name(:trigger_10ee1357e825)1366main: -> 0.0000s1367main: -- execute("ALTER FUNCTION \"trigger_10ee1357e825\" RESET ALL")1368main: -> 0.0010s1369main: -- execute("LOCK TABLE ci_pipelines, p_ci_builds IN ACCESS EXCLUSIVE MODE")1370main: -> 0.0021s1371main: -- remove_foreign_key(:p_ci_builds, :ci_pipelines, {:name=>:fk_a2141b1522})1372main: -> 0.0084s1373main: -- quote_table_name("public.ci_builds")1374main: -> 0.0000s1375main: -- quote_column_name(:fk_dd3c83bdee)1376main: -> 0.0000s1377main: -- quote_column_name(:fk_a2141b1522)1378main: -> 0.0000s1379main: -- execute("ALTER TABLE \"public\".\"ci_builds\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")1380main: -> 0.0014s1381main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_101")1382main: -> 0.0000s1383main: -- quote_column_name(:fk_dd3c83bdee)1384main: -> 0.0000s1385main: -- quote_column_name(:fk_a2141b1522)1386main: -> 0.0000s1387main: -- execute("ALTER TABLE \"gitlab_partitions_dynamic\".\"ci_builds_101\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")1388main: -> 0.0010s1389main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_102")1390main: -> 0.0000s1391main: -- quote_column_name(:fk_dd3c83bdee)1392main: -> 0.0000s1393main: -- quote_column_name(:fk_a2141b1522)1394main: -> 0.0000s1395main: -- execute("ALTER TABLE \"gitlab_partitions_dynamic\".\"ci_builds_102\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")1396main: -> 0.0009s1397main: -- quote_table_name(:p_ci_builds)1398main: -> 0.0000s1399main: -- quote_column_name(:fk_dd3c83bdee)1400main: -> 0.0000s1401main: -- quote_column_name(:fk_a2141b1522)1402main: -> 0.0000s1403main: -- execute("ALTER TABLE \"p_ci_builds\"\nRENAME CONSTRAINT \"fk_dd3c83bdee\" TO \"fk_a2141b1522\"\n")1404main: -> 0.0009s1405main: -- indexes(:p_ci_builds)1406main: -> 0.0488s1407main: -- indexes(:p_ci_builds)1408main: -> 0.0491s1409main: -- indexes("public.ci_builds")1410main: -> 0.0496s1411main: -- indexes("public.ci_builds")1412main: -> 0.0473s1413main: -- quote_table_name("public.index_ffe1233676")1414main: -> 0.0000s1415main: -- quote_table_name("temp_b1a20ba0f9")1416main: -> 0.0000s1417main: -- execute("ALTER INDEX \"public\".\"index_ffe1233676\" RENAME TO \"temp_b1a20ba0f9\"")1418main: -> 0.0013s1419main: -- quote_table_name("public.index_ci_builds_on_auto_canceled_by_id")1420main: -> 0.0000s1421main: -- quote_table_name("index_ffe1233676")1422main: -> 0.0000s1423main: -- execute("ALTER INDEX \"public\".\"index_ci_builds_on_auto_canceled_by_id\" RENAME TO \"index_ffe1233676\"")1424main: -> 0.0009s1425main: -- quote_table_name("public.temp_b1a20ba0f9")1426main: -> 0.0000s1427main: -- quote_table_name("index_ci_builds_on_auto_canceled_by_id")1428main: -> 0.0000s1429main: -- execute("ALTER INDEX \"public\".\"temp_b1a20ba0f9\" RENAME TO \"index_ci_builds_on_auto_canceled_by_id\"")1430main: -> 0.0010s1431main: -- indexes("gitlab_partitions_dynamic.ci_builds_101")1432main: -> 0.0485s1433main: -- indexes("gitlab_partitions_dynamic.ci_builds_101")1434main: -> 0.0481s1435main: -- quote_table_name("gitlab_partitions_dynamic.index_be05aa07e1")1436main: -> 0.0000s1437main: -- quote_table_name("temp_64259f0ae9")1438main: -> 0.0000s1439main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"index_be05aa07e1\" RENAME TO \"temp_64259f0ae9\"")1440main: -> 0.0012s1441main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_101_auto_canceled_by_id_idx")1442main: -> 0.0000s1443main: -- quote_table_name("index_be05aa07e1")1444main: -> 0.0000s1445main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"ci_builds_101_auto_canceled_by_id_idx\" RENAME TO \"index_be05aa07e1\"")1446main: -> 0.0011s1447main: -- quote_table_name("gitlab_partitions_dynamic.temp_64259f0ae9")1448main: -> 0.0000s1449main: -- quote_table_name("ci_builds_101_auto_canceled_by_id_idx")1450main: -> 0.0000s1451main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"temp_64259f0ae9\" RENAME TO \"ci_builds_101_auto_canceled_by_id_idx\"")1452main: -> 0.0009s1453main: -- indexes("gitlab_partitions_dynamic.ci_builds_102")1454main: -> 0.0491s1455main: -- indexes("gitlab_partitions_dynamic.ci_builds_102")1456main: -> 0.0477s1457main: -- quote_table_name("gitlab_partitions_dynamic.index_8d72ef5f95")1458main: -> 0.0000s1459main: -- quote_table_name("temp_a0f25a97bc")1460main: -> 0.0000s1461main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"index_8d72ef5f95\" RENAME TO \"temp_a0f25a97bc\"")1462main: -> 0.0012s1463main: -- quote_table_name("gitlab_partitions_dynamic.ci_builds_102_auto_canceled_by_id_idx")1464main: -> 0.0000s1465main: -- quote_table_name("index_8d72ef5f95")1466main: -> 0.0000s1467main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"ci_builds_102_auto_canceled_by_id_idx\" RENAME TO \"index_8d72ef5f95\"")1468main: -> 0.0010s1469main: -- quote_table_name("gitlab_partitions_dynamic.temp_a0f25a97bc")1470main: -> 0.0000s1471main: -- quote_table_name("ci_builds_102_auto_canceled_by_id_idx")1472main: -> 0.0000s1473main: -- execute("ALTER INDEX \"gitlab_partitions_dynamic\".\"temp_a0f25a97bc\" RENAME TO \"ci_builds_102_auto_canceled_by_id_idx\"")1474main: -> 0.0010s1475main: -- quote_table_name("p_ci_builds_auto_canceled_by_id_bigint_idx")1476main: -> 0.0000s1477main: -- quote_table_name("temp_3f76d2212d")1478main: -> 0.0000s1479main: -- execute("ALTER INDEX \"p_ci_builds_auto_canceled_by_id_bigint_idx\" RENAME TO \"temp_3f76d2212d\"")1480main: -> 0.0011s1481main: -- quote_table_name("p_ci_builds_auto_canceled_by_id_idx")1482main: -> 0.0000s1483main: -- quote_table_name(:p_ci_builds_auto_canceled_by_id_bigint_idx)1484main: -> 0.0000s1485main: -- execute("ALTER INDEX \"p_ci_builds_auto_canceled_by_id_idx\" RENAME TO \"p_ci_builds_auto_canceled_by_id_bigint_idx\"")1486main: -> 0.0010s1487main: -- quote_table_name("temp_3f76d2212d")1488main: -> 0.0000s1489main: -- quote_table_name(:p_ci_builds_auto_canceled_by_id_idx)1490main: -> 0.0000s1491main: -- execute("ALTER INDEX \"temp_3f76d2212d\" RENAME TO \"p_ci_builds_auto_canceled_by_id_idx\"")1492main: -> 0.0010s1493main: -- remove_index(:p_ci_builds, {:name=>:p_ci_builds_auto_canceled_by_id_bigint_idx})1494main: -> 0.0014s1495main: == 20240219040351 SwapColumnsForAutoCanceledByIdBetweenCiBuildsAndCiPipelines: migrated (0.5602s) 1496main: == [advisory_lock_connection] object_id: 255508140, pg_backend_pid: 3191497 correctly swaps conversion columns1498# [RSpecRunTime] RSpec elapsed time: 22 minutes 48.65 seconds. Current RSS: ~1187M. load average: 1.05 1.06 1.01 1/269 4271499INFO: analyzing "public.p_ci_stages" inheritance tree1500INFO: analyzing "public.ci_stages"1501INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1502I, [2024-05-24T16:07:42.552977 #340] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1503I, [2024-05-24T16:07:42.554188 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1504I, [2024-05-24T16:07:42.555177 #340] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1505I, [2024-05-24T16:07:43.714326 #340] INFO -- : Database: 'main', Table: 'p_ci_build_names': Lock Writes1506I, [2024-05-24T16:07:49.887352 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1507# [RSpecRunTime] Finishing example group spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb. It took 1 minute 8.1 seconds. Expected to take 36.8 seconds.1508# [RSpecRunTime] RSpec elapsed time: 23 minutes 18.52 seconds. Current RSS: ~1158M. load average: 0.98 1.04 1.00 1/269 4281509# [RSpecRunTime] Starting example group spec/migrations/20240306120522_queue_update_sbom_occurrences_component_name_based_on_pep503_spec.rb. Expected to take 29.81 seconds.1510QueueUpdateSbomOccurrencesComponentNameBasedOnPep5031511main: == [advisory_lock_connection] object_id: 274692240, pg_backend_pid: 3331512main: == 20240306120522 QueueUpdateSbomOccurrencesComponentNameBasedOnPep503: migrating 1513main: == 20240306120522 QueueUpdateSbomOccurrencesComponentNameBasedOnPep503: migrated (0.0668s) 1514main: == [advisory_lock_connection] object_id: 274692240, pg_backend_pid: 3331515 schedules a new batched migration1516I, [2024-05-24T16:08:50.078634 #340] INFO -- : Database: 'main', Table: 'p_ci_builds_execution_configs': Lock Writes1517# [RSpecRunTime] Finishing example group spec/migrations/20240306120522_queue_update_sbom_occurrences_component_name_based_on_pep503_spec.rb. It took 1 minute 0.24 second. Expected to take 29.81 seconds.1518# [RSpecRunTime] RSpec elapsed time: 24 minutes 18.86 seconds. Current RSS: ~1154M. load average: 1.03 1.04 1.00 1/269 4291519# [RSpecRunTime] Starting example group spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb. Expected to take 18.3 seconds.1520SetTrustedExternUidToFalseForExistingBitbucketIdentities1521 #up1522main: == [advisory_lock_connection] object_id: 289655980, pg_backend_pid: 3441523main: == 20240419140530 SetTrustedExternUidToFalseForExistingBitbucketIdentities: migrating 1524main: == 20240419140530 SetTrustedExternUidToFalseForExistingBitbucketIdentities: migrated (0.0301s) 1525main: == [advisory_lock_connection] object_id: 289655980, pg_backend_pid: 3441526 sets trusted_extern_uid to false for existing bitbucket identities1527# [RSpecRunTime] RSpec elapsed time: 24 minutes 38.12 seconds. Current RSS: ~1179M. load average: 1.23 1.09 1.02 1/269 4301528# [RSpecRunTime] Finishing example group spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb. It took 36.64 seconds. Expected to take 18.3 seconds.1529# [RSpecRunTime] RSpec elapsed time: 24 minutes 55.58 seconds. Current RSS: ~1166M. load average: 1.11 1.07 1.01 1/269 4311530auto_explain log contains 918 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-13-15.340.main.ndjson.gz1531took 36.2771793381532auto_explain log contains 919 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-13-15.340.ci.ndjson.gz1533took 32.0646508071534[TEST PROF INFO] EventProf results for sql.active_record1535Total time: 12:52.922 of 24:49.580 (51.89%)1536Total events: 6198001537Top 5 slowest suites (by time):1538EnsureDumNote...ForSelfManaged (./spec/migrations/20230816152540_ensure_dum_note_id_bigint_backfill_is_finished_for_self_managed_spec.rb:7) – 01:39.216 (71569 / 2) of 02:52.306 (57.58%)1539SwapEventsTar...ntForSelfHosts (./spec/migrations/20230817111938_swap_events_target_id_to_bigint_for_self_hosts_spec.rb:6) – 01:36.196 (75984 / 4) of 04:03.272 (39.54%)1540SwapIssueUser...ForSelfManaged (./spec/migrations/20230809210550_swap_issue_user_mentions_note_id_to_bigint_for_self_managed_spec.rb:6) – 01:34.524 (76498 / 4) of 04:04.070 (38.73%)1541QueueBackfill...erabilityReads (./spec/migrations/20230907155247_queue_backfill_has_merge_request_of_vulnerability_reads_spec.rb:6) – 01:28.396 (66853 / 1) of 02:25.743 (60.65%)1542SwapColumnsFo...pelineIdBigint (./spec/migrations/db/post_migrate/20231004091113_swap_columns_for_ci_sources_pipelines_pipeline_id_bigint_spec.rb:6) – 01:26.785 (65796 / 2) of 02:59.274 (48.41%)1543Knapsack report was generated. Preview:1544{1545 "spec/migrations/20230817111938_swap_events_target_id_to_bigint_for_self_hosts_spec.rb": 181.63941881999926,1546 "spec/migrations/20230809210550_swap_issue_user_mentions_note_id_to_bigint_for_self_managed_spec.rb": 181.17126324499986,1547 "spec/migrations/db/post_migrate/20231004091113_swap_columns_for_ci_sources_pipelines_pipeline_id_bigint_spec.rb": 114.82655774500017,1548 "spec/migrations/20230816152540_ensure_dum_note_id_bigint_backfill_is_finished_for_self_managed_spec.rb": 104.61233968099987,1549 "spec/migrations/20230907155247_queue_backfill_has_merge_request_of_vulnerability_reads_spec.rb": 85.066721494999,1550 "spec/migrations/20231102142554_migrate_zoekt_shards_to_zoekt_nodes_spec.rb": 74.87181334600064,1551 "spec/migrations/20231030071209_queue_backfill_packages_tags_project_id_spec.rb": 70.77732156299862,1552 "spec/migrations/20240212172655_self_hosted_sent_notifications_cleanup_spec.rb": 56.836247852999804,1553 "spec/migrations/db/post_migrate/20240214040351_swap_columns_for_auto_canceled_by_id_between_ci_builds_and_ci_pipelines_spec.rb": 38.40394378199926,1554 "spec/migrations/20240306120522_queue_update_sbom_occurrences_component_name_based_on_pep503_spec.rb": 32.976202918000126,1555 "spec/migrations/20240419140530_set_trusted_extern_uid_to_false_for_existing_bitbucket_identities_spec.rb": 19.3481745970002521556}1557Knapsack global time execution for tests: 16m1558Finished in 26 minutes 4 seconds (files took 1 minute 54.44 seconds to load)155922 examples, 0 failures1560Randomized with seed 146991561[TEST PROF INFO] Time spent in factories: 00:00.188 (0.01% of total time)1562RSpec exited with 0.1563No examples to retry, congrats!1565Running after script...1566$ source scripts/utils.sh1567$ bundle exec gem list gitlab_quality-test_tooling1568gitlab_quality-test_tooling (1.26.0)1569$ section_start "failed-test-issues" "Report test failures" # collapsed multi-line command1573$ section_start "flaky-test-issues" "Report test flakiness" # collapsed multi-line command1576$ section_start "slow-test-issues" "Report test slowness" # collapsed multi-line command1580$ section_start "slow-test-merge-request-report-note" "Report test slowness in MR note" # collapsed multi-line command1583$ section_start "knapsack-report-issues" "Report test files close to timing out" # collapsed multi-line command1587$ tooling/bin/push_job_metrics || true1588[job-metrics] Pushing job metrics file for the CI/CD job.1589[job-metrics] Pushed 4 CI job metric entries to InfluxDB.1591Not uploading cache ruby-gems-debian-bookworm-ruby-3.1-17 due to policy1593Uploading artifacts...1594auto_explain/: found 3 matching artifact files and directories 1595coverage/: found 5 matching artifact files and directories 1596WARNING: crystalball/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1597WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1598knapsack/: found 4 matching artifact files and directories 1599rspec/: found 12 matching artifact files and directories 1600WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1601log/*.log: found 13 matching artifact files and directories 1602WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6934018097/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com1603WARNING: Retrying... context=artifacts-uploader error=request redirected1604Uploading artifacts as "archive" to coordinator... 201 Created id=6934018097 responseStatus=201 Created token=glcbt-651605Uploading artifacts...1606rspec/rspec-*.xml: found 1 matching artifact files and directories 1607WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6934018097/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com1608WARNING: Retrying... context=artifacts-uploader error=request redirected1609Uploading artifacts as "junit" to coordinator... 201 Created id=6934018097 responseStatus=201 Created token=glcbt-651611Job succeeded