rspec migration pg14 1/15
Passed Started
by
@rkadam3

Rajendra Kadam
1Running with gitlab-runner 16.9.1 (782c6ecb)2 on green-5.private.runners-manager.gitlab.com/gitlab.com/gitlab-org Zi_8oswQ, system ID: s_8a4f217c24b63 feature flags: FF_NETWORK_PER_BUILD:true, FF_USE_IMPROVED_URL_MASKING:true6Using Docker executor with image registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-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:b6c412dc298714b5c26feca1330d8f743b48caf0b1feeadfd554d7d446f985a5 for redis:6.2-alpine with digest redis@sha256:51d6c56749a4243096327e3fb964a48ed92254357108449cb6e23999c37773c5 ...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.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-yarn-1.22-graphicsmagick-1.3.36 ...23Using docker image sha256:fc7435a87025ef82fcd7c3a7df1e52121e8a88ab05430c94ba7ad5920fecc0d0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-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.20-rust-1.73-node-18.17-postgresql-14@sha256:c129fa1214137f01be7f1412c5233ac179604d32d9d852cbe06113596fb24504 ...25Running on runner-zi8oswq-project-278964-concurrent-0 via runner-zi8oswq-private-1709554730-b03b2385...27Skipping Git repository setup28Skipping Git checkout29Skipping Git submodules setup31Checking cache for ruby-gems-debian-bookworm-ruby-3.1-17...32cache.zip is up to date 33Successfully extracted cache35Downloading artifacts for clone-gitlab-repo (6311410718)...36Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410718 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 (6311410765)...40Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410765 responseStatus=200 OK token=glcbt-6541Downloading artifacts for detect-tests (6311410787)...42Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410787 responseStatus=200 OK token=glcbt-6543Downloading artifacts for retrieve-tests-metadata (6311410799)...44Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410799 responseStatus=200 OK token=glcbt-6545Downloading artifacts for setup-test-env (6311410773)...46Downloading artifacts from coordinator... ok host=storage.googleapis.com id=6311410773 responseStatus=200 OK token=glcbt-6548Using docker image sha256:fc7435a87025ef82fcd7c3a7df1e52121e8a88ab05430c94ba7ad5920fecc0d0 for registry.gitlab.com/gitlab-org/gitlab-build-images/debian-bookworm-ruby-3.1.patched-golang-1.20-rust-1.73-node-18.17-postgresql-14:rubygems-3.4-git-2.36-lfs-2.9-chrome-120-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.20-rust-1.73-node-18.17-postgresql-14@sha256:c129fa1214137f01be7f1412c5233ac179604d32d9d852cbe06113596fb24504 ...49$ echo $FOSS_ONLY50$ [ "$FOSS_ONLY" = "1" ] && rm -rf ee/ qa/spec/ee/ qa/qa/specs/features/ee/ qa/qa/ee/ qa/qa/ee.rb51$ export GOPATH=$CI_PROJECT_DIR/.go52$ mkdir -p $GOPATH53$ source scripts/utils.sh54$ source scripts/prepare_build.sh732Using decomposed database config (config/database.yml.decomposed-postgresql)733Geo DB won't be set up.734Embedding DB won't be set up.746$ source ./scripts/rspec_helpers.sh747$ run_timed_command "gem install knapsack --no-document"748$ gem install knapsack --no-document749Successfully installed knapsack-4.0.07501 gem installed751==> 'gem install knapsack --no-document' succeeded in 1 seconds.752$ section_start "gitaly-test-spawn" "Spawning Gitaly"; scripts/gitaly-test-spawn; section_end "gitaly-test-spawn"757$ export RSPEC_SKIPPED_TESTS_REPORT_PATH="rspec/skipped_tests-${CI_JOB_ID}.txt"758$ export RSPEC_RETRIED_TESTS_REPORT_PATH="rspec/retried_tests-${CI_JOB_ID}.txt"759$ tooling/bin/create_job_metrics_file || true760[job-metrics] Creating the job metrics file for the CI/CD job.761$ rspec_parallelized_job "--fail-fast=${RSPEC_FAIL_FAST_THRESHOLD} --tag ~quarantine --tag ~zoekt --tag ~click_house"762[13:29:42] Starting rspec_parallelized_job763RETRY_FAILED_TESTS_IN_NEW_PROCESS: true764KNAPSACK_GENERATE_REPORT: true765FLAKY_RSPEC_GENERATE_REPORT: true766KNAPSACK_TEST_FILE_PATTERN: spec/{migrations}{,/**/}*_spec.rb767KNAPSACK_LOG_LEVEL: debug768KNAPSACK_REPORT_PATH: knapsack/rspec_migration_pg14_1_15_278964_report.json769FLAKY_RSPEC_SUITE_REPORT_PATH: rspec/flaky/report-suite.json770FLAKY_RSPEC_REPORT_PATH: rspec/flaky/all_rspec_migration_pg14_1_15_278964_report.json771NEW_FLAKY_RSPEC_REPORT_PATH: rspec/flaky/new_rspec_migration_pg14_1_15_278964_report.json772RSPEC_SKIPPED_TESTS_REPORT_PATH: rspec/skipped_tests-6311411054.txt773CRYSTALBALL: 774RSPEC_TESTS_MAPPING_ENABLED: 775RSPEC_TESTS_FILTER_FILE: 776Shell set options (set -o) enabled:777braceexpand on778hashall on779interactive-comments on780pipefail on781Knapsack report generator started!782Run options: exclude {:quarantine=>true, :zoekt=>true, :click_house=>true}783# [RSpecRunTime] Starting RSpec timer...784[TEST PROF INFO] EventProf enabled (sql.active_record)785unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.786unknown OID 2249: failed to recognize type of 'pg_stat_file'. It will be treated as String.787Test environment set up in 1.411691179 seconds788# [RSpecRunTime] Starting example group spec/migrations/20230214181633_finalize_ci_build_needs_big_int_conversion_spec.rb. Expected to take 3 minutes 28.76 seconds.789FinalizeCiBuildNeedsBigIntConversion790 #up791 dot_com: true, dev_or_test: true, jh: true, expectation: :not_to792ci: == [advisory_lock_connection] object_id: 51563560, pg_backend_pid: 2064793ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============794ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0224s) ====795ci: == [advisory_lock_connection] object_id: 51563560, pg_backend_pid: 2064796 ensures the migration is completed for GitLab.com, dev, or test797 dot_com: true, dev_or_test: false, jh: true, expectation: :not_to798ci: == [advisory_lock_connection] object_id: 53216600, pg_backend_pid: 2070799ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============800ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0248s) ====801ci: == [advisory_lock_connection] object_id: 53216600, pg_backend_pid: 2070802 ensures the migration is completed for GitLab.com, dev, or test803# [RSpecRunTime] RSpec elapsed time: 3 minutes 1.83 seconds. Current RSS: ~1401M. load average: 0.94 1.20 1.31 1/270 384804 dot_com: false, dev_or_test: true, jh: true, expectation: :not_to805ci: == [advisory_lock_connection] object_id: 54336420, pg_backend_pid: 2077806ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============807ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0248s) ====808ci: == [advisory_lock_connection] object_id: 54336420, pg_backend_pid: 2077809 ensures the migration is completed for GitLab.com, dev, or test810# [RSpecRunTime] RSpec elapsed time: 3 minutes 4.82 seconds. Current RSS: ~1364M. load average: 0.94 1.20 1.31 1/270 385811 dot_com: false, dev_or_test: false, jh: true, expectation: :not_to812ci: == [advisory_lock_connection] object_id: 54855900, pg_backend_pid: 2083813ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============814ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0216s) ====815ci: == [advisory_lock_connection] object_id: 54855900, pg_backend_pid: 2083816 ensures the migration is completed for GitLab.com, dev, or test817# [RSpecRunTime] RSpec elapsed time: 3 minutes 7.7 seconds. Current RSS: ~1359M. load average: 0.87 1.18 1.30 1/270 386818 dot_com: true, dev_or_test: true, jh: false, expectation: :to819ci: == [advisory_lock_connection] object_id: 55382500, pg_backend_pid: 2089820ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============821ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0234s) ====822ci: == [advisory_lock_connection] object_id: 55382500, pg_backend_pid: 2089823 ensures the migration is completed for GitLab.com, dev, or test824# [RSpecRunTime] RSpec elapsed time: 3 minutes 10.59 seconds. Current RSS: ~1359M. load average: 0.87 1.18 1.30 1/270 387825 dot_com: true, dev_or_test: false, jh: false, expectation: :to826ci: == [advisory_lock_connection] object_id: 55935340, pg_backend_pid: 2095827ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============828ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0224s) ====829ci: == [advisory_lock_connection] object_id: 55935340, pg_backend_pid: 2095830 ensures the migration is completed for GitLab.com, dev, or test831# [RSpecRunTime] RSpec elapsed time: 3 minutes 13.43 seconds. Current RSS: ~1360M. load average: 0.88 1.18 1.30 1/270 388832 dot_com: false, dev_or_test: true, jh: false, expectation: :to833ci: == [advisory_lock_connection] object_id: 56486400, pg_backend_pid: 2101834ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============835ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0251s) ====836ci: == [advisory_lock_connection] object_id: 56486400, pg_backend_pid: 2101837 ensures the migration is completed for GitLab.com, dev, or test838# [RSpecRunTime] RSpec elapsed time: 3 minutes 16.37 seconds. Current RSS: ~1361M. load average: 0.89 1.18 1.30 1/270 389839 dot_com: false, dev_or_test: false, jh: false, expectation: :not_to840ci: == [advisory_lock_connection] object_id: 57037000, pg_backend_pid: 2107841ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrating =============842ci: == 20230214181633 FinalizeCiBuildNeedsBigIntConversion: migrated (0.0230s) ====843ci: == [advisory_lock_connection] object_id: 57037000, pg_backend_pid: 2107844 ensures the migration is completed for GitLab.com, dev, or test845# [RSpecRunTime] RSpec elapsed time: 3 minutes 19.22 seconds. Current RSS: ~1360M. load average: 0.89 1.18 1.30 1/271 390846I, [2024-03-04T13:33:47.267539 #338] INFO -- : Database: 'main', Table: 'p_ci_runner_machine_builds': Lock Writes847I, [2024-03-04T13:33:47.539015 #338] INFO -- : Database: 'main', Table: 'ci_cost_settings': Lock Writes848INFO: analyzing "public.p_ci_builds" inheritance tree849INFO: analyzing "public.ci_builds"850INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows851I, [2024-03-04T13:34:02.094422 #338] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes852I, [2024-03-04T13:34:02.095432 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}853I, [2024-03-04T13:34:02.096169 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}854I, [2024-03-04T13:34:14.883240 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes855INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree856INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"857INFO: "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 rows858INFO: analyzing "public.p_ci_job_annotations" inheritance tree859INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"860INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows861INFO: analyzing "public.p_ci_builds_metadata" inheritance tree862INFO: analyzing "public.ci_builds_metadata"863INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows864INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"865INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows866INFO: analyzing "public.p_ci_builds" inheritance tree867INFO: analyzing "public.ci_builds"868INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows869I, [2024-03-04T13:34:38.065540 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes870INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree871INFO: analyzing "public.ci_pipeline_variables"872INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows873I, [2024-03-04T13:34:58.078771 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes874I, [2024-03-04T13:34:58.079880 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}875I, [2024-03-04T13:34:58.080682 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}876I, [2024-03-04T13:34:58.561780 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes877INFO: analyzing "public.p_ci_job_artifacts" inheritance tree878INFO: analyzing "public.ci_job_artifacts"879INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows880I, [2024-03-04T13:34:59.897637 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes881I, [2024-03-04T13:34:59.899090 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}882I, [2024-03-04T13:34:59.899929 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}883INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree884INFO: analyzing "public.ci_pipeline_variables"885INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows886INFO: analyzing "public.p_ci_stages" inheritance tree887INFO: analyzing "public.ci_stages"888INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows889I, [2024-03-04T13:35:05.048591 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes890I, [2024-03-04T13:35:05.049824 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}891I, [2024-03-04T13:35:05.050650 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}892I, [2024-03-04T13:35:11.732502 #338] INFO -- : Database: 'ci', Table: 'catalog_resources': Lock Writes893I, [2024-03-04T13:35:14.032056 #338] INFO -- : Database: 'ci', Table: 'design_management_repositories': Lock Writes894I, [2024-03-04T13:35:14.544406 #338] INFO -- : Database: 'ci', Table: 'container_registry_data_repair_details': Lock Writes895I, [2024-03-04T13:35:14.991978 #338] INFO -- : Database: 'ci', Table: 'search_indices': Lock Writes896I, [2024-03-04T13:35:15.056890 #338] INFO -- : Database: 'ci', Table: 'search_namespace_index_assignments': Lock Writes897I, [2024-03-04T13:35:15.128061 #338] INFO -- : Database: 'ci', Table: 'resource_link_events': Lock Writes898I, [2024-03-04T13:35:15.418524 #338] INFO -- : Database: 'ci', Table: 'packages_npm_metadata_caches': Lock Writes899I, [2024-03-04T13:35:15.812471 #338] INFO -- : Database: 'ci', Table: 'service_desk_custom_email_credentials': Lock Writes900I, [2024-03-04T13:35:17.480019 #338] INFO -- : Database: 'ci', Table: 'design_management_repository_states': Lock Writes901I, [2024-03-04T13:35:17.884208 #338] INFO -- : Database: 'ci', Table: 'dora_performance_scores': Lock Writes902I, [2024-03-04T13:35:20.606945 #338] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_audit_event_destinations': Lock Writes903I, [2024-03-04T13:35:20.779997 #338] INFO -- : Database: 'ci', Table: 'agent_user_access_project_authorizations': Lock Writes904I, [2024-03-04T13:35:20.807406 #338] INFO -- : Database: 'ci', Table: 'agent_user_access_group_authorizations': Lock Writes905I, [2024-03-04T13:35:21.131558 #338] INFO -- : Database: 'ci', Table: 'abuse_trust_scores': Lock Writes906I, [2024-03-04T13:35:21.178949 #338] INFO -- : Database: 'ci', Table: 'pm_advisories': Lock Writes907I, [2024-03-04T13:35:21.180897 #338] INFO -- : Database: 'ci', Table: 'pm_affected_packages': Lock Writes908I, [2024-03-04T13:35:21.230477 #338] INFO -- : Database: 'ci', Table: 'issue_assignment_events': Lock Writes909I, [2024-03-04T13:35:22.241799 #338] INFO -- : Database: 'ci', Table: 'merge_request_assignment_events': Lock Writes910I, [2024-03-04T13:35:22.731850 #338] INFO -- : Database: 'ci', Table: 'project_states': Lock Writes911I, [2024-03-04T13:35:23.256398 #338] INFO -- : Database: 'ci', Table: 'organizations': Lock Writes912I, [2024-03-04T13:35:23.807133 #338] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_counts': Lock Writes913I, [2024-03-04T13:35:23.918996 #338] INFO -- : Database: 'ci', Table: 'note_metadata': Lock Writes914I, [2024-03-04T13:35:24.240744 #338] INFO -- : Database: 'ci', Table: 'merge_request_diff_llm_summaries': Lock Writes915I, [2024-03-04T13:35:24.604542 #338] INFO -- : Database: 'ci', Table: 'value_stream_dashboard_aggregations': Lock Writes916I, [2024-03-04T13:35:24.773062 #338] INFO -- : Database: 'ci', Table: 'abuse_report_events': Lock Writes917I, [2024-03-04T13:35:25.723787 #338] INFO -- : Database: 'ci', Table: 'audit_events_google_cloud_logging_configurations': Lock Writes918INFO: analyzing "public.p_ci_builds" inheritance tree919INFO: analyzing "public.ci_builds"920INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows921I, [2024-03-04T13:35:28.368446 #338] INFO -- : Database: 'ci', Table: 'dependency_proxy_packages_settings': Lock Writes922I, [2024-03-04T13:35:28.369506 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}923I, [2024-03-04T13:35:28.370271 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}924I, [2024-03-04T13:35:28.451027 #338] INFO -- : Database: 'ci', Table: 'instance_audit_events_streaming_headers': Lock Writes925I, [2024-03-04T13:35:29.730224 #338] INFO -- : Database: 'ci', Table: 'abuse_events': Lock Writes926I, [2024-03-04T13:35:40.243707 #338] INFO -- : Database: 'ci', Table: 'subscription_add_ons': Lock Writes927I, [2024-03-04T13:35:40.265459 #338] INFO -- : Database: 'ci', Table: 'subscription_add_on_purchases': Lock Writes928I, [2024-03-04T13:35:40.537550 #338] INFO -- : Database: 'ci', Table: 'project_compliance_standards_adherence': Lock Writes929I, [2024-03-04T13:35:42.309029 #338] INFO -- : Database: 'ci', Table: 'organization_settings': Lock Writes930I, [2024-03-04T13:35:42.546597 #338] INFO -- : Database: 'ci', Table: 'organization_users': Lock Writes931I, [2024-03-04T13:35:43.142841 #338] INFO -- : Database: 'ci', Table: 'audit_events_streaming_instance_event_type_filters': Lock Writes932I, [2024-03-04T13:35:45.442647 #338] INFO -- : Database: 'ci', Table: 'subscription_user_add_on_assignments': Lock Writes933I, [2024-03-04T13:35:45.557433 #338] INFO -- : Database: 'ci', Table: 'system_access_microsoft_applications': Lock Writes934I, [2024-03-04T13:35:45.583877 #338] INFO -- : Database: 'ci', Table: 'system_access_microsoft_graph_access_tokens': Lock Writes935I, [2024-03-04T13:35:46.810919 #338] INFO -- : Database: 'ci', Table: 'merge_request_review_llm_summaries': Lock Writes936I, [2024-03-04T13:35:47.557793 #338] INFO -- : Database: 'ci', Table: 'catalog_resource_versions': Lock Writes937I, [2024-03-04T13:35:48.701422 #338] INFO -- : Database: 'ci', Table: 'p_batched_git_ref_updates_deletions': Lock Writes938I, [2024-03-04T13:35:48.723757 #338] INFO -- : Database: 'ci', Table: 'service_access_tokens': Lock Writes939I, [2024-03-04T13:35:48.749816 #338] INFO -- : Database: 'ci', Table: 'ml_models': Lock Writes940I, [2024-03-04T13:35:49.224519 #338] INFO -- : Database: 'ci', Table: 'ml_model_versions': Lock Writes941I, [2024-03-04T13:35:51.314132 #338] INFO -- : Database: 'ci', Table: 'group_ssh_certificates': Lock Writes942I, [2024-03-04T13:35:51.466794 #338] INFO -- : Database: 'ci', Table: 'group_wiki_repository_states': Lock Writes943I, [2024-03-04T13:35:51.720852 #338] INFO -- : Database: 'ci', Table: 'target_branch_rules': Lock Writes944I, [2024-03-04T13:35:55.286818 #338] INFO -- : Database: 'ci', Table: 'catalog_resource_components': Lock Writes945I, [2024-03-04T13:36:06.416002 #338] INFO -- : Database: 'ci', Table: 'workspace_variables': Lock Writes946I, [2024-03-04T13:36:06.590794 #338] INFO -- : Database: 'ci', Table: 'packages_nuget_symbols': Lock Writes947I, [2024-03-04T13:36:07.001148 #338] INFO -- : Database: 'ci', Table: 'audit_events_instance_google_cloud_logging_configurations': Lock Writes948I, [2024-03-04T13:36:08.531528 #338] INFO -- : Database: 'ci', Table: 'packages_protection_rules': Lock Writes949I, [2024-03-04T13:36:08.889347 #338] INFO -- : Database: 'ci', Table: 'projects_visits': Lock Writes950I, [2024-03-04T13:36:08.914424 #338] INFO -- : Database: 'ci', Table: 'groups_visits': Lock Writes951I, [2024-03-04T13:36:08.943495 #338] INFO -- : Database: 'ci', Table: 'audit_events_amazon_s3_configurations': Lock Writes952INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree953INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"954INFO: "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 rows955INFO: analyzing "public.p_ci_job_annotations" inheritance tree956INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"957INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows958INFO: analyzing "public.p_ci_builds_metadata" inheritance tree959INFO: analyzing "public.ci_builds_metadata"960INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows961INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"962INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows963INFO: analyzing "public.p_ci_builds" inheritance tree964INFO: analyzing "public.ci_builds"965INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows966I, [2024-03-04T13:36:09.865280 #338] INFO -- : Database: 'ci', Table: 'vs_code_settings': Lock Writes967I, [2024-03-04T13:36:10.456391 #338] INFO -- : Database: 'ci', Table: 'scan_result_policy_violations': Lock Writes968I, [2024-03-04T13:36:11.591608 #338] INFO -- : Database: 'ci', Table: 'approval_group_rules': Lock Writes969I, [2024-03-04T13:36:11.592697 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}970I, [2024-03-04T13:36:11.593385 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}971I, [2024-03-04T13:36:11.617481 #338] INFO -- : Database: 'ci', Table: 'approval_group_rules_groups': Lock Writes972I, [2024-03-04T13:36:11.641206 #338] INFO -- : Database: 'ci', Table: 'approval_group_rules_users': Lock Writes973I, [2024-03-04T13:36:11.663873 #338] INFO -- : Database: 'ci', Table: 'approval_group_rules_protected_branches': Lock Writes974I, [2024-03-04T13:36:12.005635 #338] INFO -- : Database: 'ci', Table: 'analytics_cycle_analytics_value_stream_settings': Lock Writes975I, [2024-03-04T13:36:14.460035 #338] INFO -- : Database: 'ci', Table: 'work_item_related_link_restrictions': Lock Writes976I, [2024-03-04T13:36:14.691046 #338] INFO -- : Database: 'ci', Table: 'abuse_report_user_mentions': Lock Writes977I, [2024-03-04T13:36:15.113881 #338] INFO -- : Database: 'ci', Table: 'container_registry_protection_rules': Lock Writes978I, [2024-03-04T13:36:16.639031 #338] INFO -- : Database: 'ci', Table: 'activity_pub_releases_subscriptions': Lock Writes979I, [2024-03-04T13:36:16.685071 #338] INFO -- : Database: 'ci', Table: 'ml_model_metadata': Lock Writes980I, [2024-03-04T13:36:17.521613 #338] INFO -- : Database: 'ci', Table: 'audit_events_streaming_http_group_namespace_filters': Lock Writes981I, [2024-03-04T13:36:18.527555 #338] INFO -- : Database: 'ci', Table: 'compliance_framework_security_policies': Lock Writes982I, [2024-03-04T13:36:19.608123 #338] INFO -- : Database: 'ci', Table: 'sbom_occurrences_vulnerabilities': Lock Writes983I, [2024-03-04T13:36:19.896689 #338] INFO -- : Database: 'ci', Table: 'zoekt_nodes': Lock Writes984I, [2024-03-04T13:36:20.315365 #338] INFO -- : Database: 'ci', Table: 'audit_events_instance_amazon_s3_configurations': Lock Writes985I, [2024-03-04T13:36:20.715704 #338] INFO -- : Database: 'ci', Table: 'abuse_report_assignees': Lock Writes986I, [2024-03-04T13:36:21.707318 #338] INFO -- : Database: 'ci', Table: 'audit_events_streaming_http_instance_namespace_filters': Lock Writes987I, [2024-03-04T13:36:22.269361 #338] INFO -- : Database: 'ci', Table: 'organization_details': Lock Writes988I, [2024-03-04T13:36:23.552983 #338] INFO -- : Database: 'ci', Table: 'p_catalog_resource_sync_events': Lock Writes989I, [2024-03-04T13:36:26.113622 #338] INFO -- : Database: 'ci', Table: 'xray_reports': Lock Writes990I, [2024-03-04T13:36:27.834977 #338] INFO -- : Database: 'ci', Table: 'zoekt_enabled_namespaces': Lock Writes991I, [2024-03-04T13:36:27.929396 #338] INFO -- : Database: 'ci', Table: 'zoekt_indices': Lock Writes992I, [2024-03-04T13:36:28.231100 #338] INFO -- : Database: 'ci', Table: 'work_item_dates_sources': Lock Writes993I, [2024-03-04T13:36:30.078627 #338] INFO -- : Database: 'ci', Table: 'ml_model_version_metadata': Lock Writes994I, [2024-03-04T13:36:30.673099 #338] INFO -- : Database: 'ci', Table: 'work_item_colors': Lock Writes995I, [2024-03-04T13:36:30.920534 #338] INFO -- : Database: 'ci', Table: 'sbom_source_packages': Lock Writes996I, [2024-03-04T13:36:30.921740 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}997I, [2024-03-04T13:36:30.922486 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}998I, [2024-03-04T13:36:30.948742 #338] INFO -- : Database: 'ci', Table: 'cloud_connector_access': Lock Writes999I, [2024-03-04T13:36:31.056514 #338] INFO -- : Database: 'ci', Table: 'namespace_descendants': Lock Writes1000I, [2024-03-04T13:36:32.827918 #338] INFO -- : Database: 'ci', Table: 'ai_agents': Lock Writes1001I, [2024-03-04T13:36:32.854608 #338] INFO -- : Database: 'ci', Table: 'ai_agent_versions': Lock Writes1002I, [2024-03-04T13:36:33.972663 #338] INFO -- : Database: 'ci', Table: 'audit_events_group_external_streaming_destinations': Lock Writes1003I, [2024-03-04T13:36:34.165586 #338] INFO -- : Database: 'ci', Table: 'packages_terraform_module_metadata': Lock Writes1004I, [2024-03-04T13:36:34.208823 #338] INFO -- : Database: 'ci', Table: 'member_approvals': Lock Writes1005I, [2024-03-04T13:36:34.238595 #338] INFO -- : Database: 'ci', Table: 'zoekt_repositories': Lock Writes1006INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1007INFO: analyzing "public.ci_pipeline_variables"1008INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1009INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1010INFO: analyzing "public.ci_job_artifacts"1011INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1012INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1013INFO: analyzing "public.ci_pipeline_variables"1014INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1015I, [2024-03-04T13:36:38.331691 #338] INFO -- : Database: 'ci', Table: 'zoekt_tasks': Lock Writes1016I, [2024-03-04T13:36:38.545530 #338] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_streaming_destinations': Lock Writes1017I, [2024-03-04T13:36:38.708198 #338] INFO -- : Database: 'ci', Table: 'catalog_verified_namespaces': Lock Writes1018I, [2024-03-04T13:36:38.919001 #338] INFO -- : Database: 'ci', Table: 'group_saved_replies': Lock Writes1019I, [2024-03-04T13:36:43.871962 #338] INFO -- : Database: 'ci', Table: 'remote_development_namespace_cluster_agent_mappings': Lock Writes1020I, [2024-03-04T13:36:43.873185 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1021I, [2024-03-04T13:36:43.873968 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1022INFO: analyzing "public.p_ci_stages" inheritance tree1023INFO: analyzing "public.ci_stages"1024INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1025I, [2024-03-04T13:36:45.299183 #338] INFO -- : Database: 'ci', Table: 'temp_notes_backup': Lock Writes1026# [RSpecRunTime] Finishing example group spec/migrations/20230214181633_finalize_ci_build_needs_big_int_conversion_spec.rb. It took 6 minutes 17.88 seconds. Expected to take 3 minutes 28.76 seconds.1027# [RSpecRunTime] RSpec elapsed time: 6 minutes 21.91 seconds. Current RSS: ~1382M. load average: 1.14 1.13 1.25 1/272 3921028# [RSpecRunTime] Starting example group spec/migrations/ensure_note_diff_files_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. Expected to take 1 minute 16.61 seconds.1029EnsureNoteDiffFilesBigintBackfillIsFinishedForGitlabDotCom1030 #up1031main: == [advisory_lock_connection] object_id: 101824960, pg_backend_pid: 40481032main: == 20230322023442 EnsureNoteDiffFilesBigintBackfillIsFinishedForGitlabDotCom: migrating 1033main: == 20230322023442 EnsureNoteDiffFilesBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0072s) 1034main: == [advisory_lock_connection] object_id: 101824960, pg_backend_pid: 40481035 ensures the migration is completed for GitLab.com, dev, or test1036main: == [advisory_lock_connection] object_id: 102860680, pg_backend_pid: 40501037main: == 20230322023442 EnsureNoteDiffFilesBigintBackfillIsFinishedForGitlabDotCom: migrating 1038main: == 20230322023442 EnsureNoteDiffFilesBigintBackfillIsFinishedForGitlabDotCom: migrated (0.0088s) 1039main: == [advisory_lock_connection] object_id: 102860680, pg_backend_pid: 40501040 skips the check for other instances1041# [RSpecRunTime] RSpec elapsed time: 7 minutes 29.04 seconds. Current RSS: ~1375M. load average: 1.10 1.11 1.23 1/273 3941042INFO: analyzing "public.p_ci_builds" inheritance tree1043INFO: analyzing "public.ci_builds"1044INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1045I, [2024-03-04T13:38:06.871195 #338] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1046I, [2024-03-04T13:38:06.872852 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1047I, [2024-03-04T13:38:06.896938 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1048I, [2024-03-04T13:38:20.028925 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1049INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1050INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1051INFO: "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 rows1052INFO: analyzing "public.p_ci_job_annotations" inheritance tree1053INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1054INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1055INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1056INFO: analyzing "public.ci_builds_metadata"1057INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1058INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1059INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1060INFO: analyzing "public.p_ci_builds" inheritance tree1061INFO: analyzing "public.ci_builds"1062INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1063I, [2024-03-04T13:38:43.390973 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1064INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1065INFO: analyzing "public.ci_pipeline_variables"1066INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1067I, [2024-03-04T13:39:03.483560 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1068I, [2024-03-04T13:39:03.484692 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1069I, [2024-03-04T13:39:03.485480 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1070I, [2024-03-04T13:39:03.943563 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1071INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1072INFO: analyzing "public.ci_job_artifacts"1073INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1074I, [2024-03-04T13:39:05.153000 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1075I, [2024-03-04T13:39:05.154169 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1076I, [2024-03-04T13:39:05.154933 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1077INFO: 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 rows1080INFO: analyzing "public.p_ci_stages" inheritance tree1081INFO: analyzing "public.ci_stages"1082INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1083I, [2024-03-04T13:39:10.192668 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1084I, [2024-03-04T13:39:10.193853 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1085I, [2024-03-04T13:39:10.194752 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1086# [RSpecRunTime] Finishing example group spec/migrations/ensure_note_diff_files_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb. It took 2 minutes 28.61 seconds. Expected to take 1 minute 16.61 seconds.1087# [RSpecRunTime] RSpec elapsed time: 8 minutes 50.56 seconds. Current RSS: ~1357M. load average: 0.89 1.04 1.20 2/273 3951088# [RSpecRunTime] Starting example group spec/migrations/swap_todos_note_id_to_bigint_for_gitlab_dot_com_spec.rb. Expected to take 1 minute 14.38 seconds.1089SwapTodosNoteIdToBigintForGitlabDotCom1090 #up1091 swaps the integer and bigint columns for GitLab.com, dev, or test1092 is a no-op for other instances1093# [RSpecRunTime] RSpec elapsed time: 9 minutes 56.79 seconds. Current RSS: ~1358M. load average: 1.17 1.08 1.20 1/273 3961094INFO: analyzing "public.p_ci_builds" inheritance tree1095INFO: analyzing "public.ci_builds"1096INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1097I, [2024-03-04T13:40:27.688941 #338] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1098I, [2024-03-04T13:40:27.690079 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1099I, [2024-03-04T13:40:27.690878 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1100I, [2024-03-04T13:40:41.098227 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1101INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1102INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1103INFO: "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 rows1104INFO: analyzing "public.p_ci_job_annotations" inheritance tree1105INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1106INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1107INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1108INFO: analyzing "public.ci_builds_metadata"1109INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1110INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1111INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1112INFO: analyzing "public.p_ci_builds" inheritance tree1113INFO: analyzing "public.ci_builds"1114INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1115I, [2024-03-04T13:41:04.947655 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1116INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1117INFO: analyzing "public.ci_pipeline_variables"1118INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1119I, [2024-03-04T13:41:24.729349 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1120I, [2024-03-04T13:41:24.730549 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1121I, [2024-03-04T13:41:24.731269 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1122I, [2024-03-04T13:41:25.190982 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1123INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1124INFO: analyzing "public.ci_job_artifacts"1125INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1126I, [2024-03-04T13:41:26.184766 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1127I, [2024-03-04T13:41:26.185762 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1128I, [2024-03-04T13:41:26.186529 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1129INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1130INFO: analyzing "public.ci_pipeline_variables"1131INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1132INFO: analyzing "public.p_ci_stages" inheritance tree1133INFO: analyzing "public.ci_stages"1134INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1135I, [2024-03-04T13:41:31.318567 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1136I, [2024-03-04T13:41:31.319762 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1137I, [2024-03-04T13:41:31.320593 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1138# [RSpecRunTime] Finishing example group spec/migrations/swap_todos_note_id_to_bigint_for_gitlab_dot_com_spec.rb. It took 2 minutes 20.8 seconds. Expected to take 1 minute 14.38 seconds.1139# [RSpecRunTime] RSpec elapsed time: 11 minutes 11.39 seconds. Current RSS: ~1351M. load average: 0.96 1.04 1.17 1/273 3971140# [RSpecRunTime] Starting example group spec/migrations/remove_old_async_index_table_name_length_constraint_spec.rb. Expected to take 1 minute 12.0 seconds.1141RemoveOldAsyncIndexTableNameLengthConstraint1142 .up1143main: -- transaction_open?(nil)1144main: -> 0.0001s1145main: -- transaction_open?(nil)1146main: -> 0.0001s1147main: -- execute(" ALTER TABLE postgres_async_indexes\n DROP CONSTRAINT IF EXISTS check_e64ff4359e\n")1148main: -> 0.0013s1149 allows inserting longer table names1150# [RSpecRunTime] RSpec elapsed time: 12 minutes 9.6 seconds. Current RSS: ~1362M. load average: 0.99 1.04 1.17 1/274 3981151 .down1152main: -- transaction_open?(nil)1153main: -> 0.0002s1154main: -- transaction_open?(nil)1155main: -> 0.0001s1156main: -- execute("ALTER TABLE postgres_async_indexes\nADD CONSTRAINT check_e64ff4359e\nCHECK ( char_length(table_name) <= 63 )\nNOT VALID;\n")1157main: -> 0.0013s1158main: -- execute("ALTER TABLE postgres_async_indexes VALIDATE CONSTRAINT check_e64ff4359e;")1159main: -> 0.0011s1160 disallows inserting longer table names1161main: -- transaction_open?(nil)1162main: -> 0.0001s1163main: -- transaction_open?(nil)1164main: -> 0.0001s1165main: -- execute(" ALTER TABLE postgres_async_indexes\n DROP CONSTRAINT IF EXISTS check_e64ff4359e\n")1166main: -> 0.0012s1167main: -- transaction_open?(nil)1168main: -> 0.0001s1169main: -- transaction_open?(nil)1170main: -> 0.0001s1171main: -- execute("ALTER TABLE postgres_async_indexes\nADD CONSTRAINT check_e64ff4359e\nCHECK ( char_length(table_name) <= 63 )\nNOT VALID;\n")1172main: -> 0.0016s1173main: -- execute("ALTER TABLE postgres_async_indexes VALIDATE CONSTRAINT check_e64ff4359e;")1174main: -> 0.0011s1175 cleans up records with too long table_name1176# [RSpecRunTime] RSpec elapsed time: 12 minutes 15.49 seconds. Current RSS: ~1374M. load average: 0.99 1.04 1.17 1/274 3991177I, [2024-03-04T13:42:55.344247 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1178INFO: 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 "public.p_ci_job_annotations" inheritance tree1182INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1183INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1184INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1185INFO: analyzing "public.ci_builds_metadata"1186INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1187INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1188INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1189INFO: analyzing "public.p_ci_builds" inheritance tree1190INFO: analyzing "public.ci_builds"1191INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1192I, [2024-03-04T13:43:18.961491 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1193INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1194INFO: analyzing "public.ci_pipeline_variables"1195INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1196I, [2024-03-04T13:43:38.902003 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1197I, [2024-03-04T13:43:38.903171 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1198I, [2024-03-04T13:43:38.903978 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1199I, [2024-03-04T13:43:39.389889 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1200INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1201INFO: analyzing "public.ci_job_artifacts"1202INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1203I, [2024-03-04T13:43:40.407112 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1204I, [2024-03-04T13:43:40.408377 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1205I, [2024-03-04T13:43:40.409122 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1206INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1207INFO: analyzing "public.ci_pipeline_variables"1208INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1209INFO: analyzing "public.p_ci_stages" inheritance tree1210INFO: analyzing "public.ci_stages"1211INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1212I, [2024-03-04T13:43:45.726416 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1213I, [2024-03-04T13:43:45.727708 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1214I, [2024-03-04T13:43:45.728482 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1215# [RSpecRunTime] Finishing example group spec/migrations/remove_old_async_index_table_name_length_constraint_spec.rb. It took 2 minutes 14.62 seconds. Expected to take 1 minute 12.0 seconds.1216# [RSpecRunTime] RSpec elapsed time: 13 minutes 26.04 seconds. Current RSS: ~1350M. load average: 0.97 1.02 1.15 1/274 4001217# [RSpecRunTime] Starting example group spec/migrations/20230321170823_backfill_ml_candidates_internal_id_spec.rb. Expected to take 1 minute 7.41 seconds.1218BackfillMlCandidatesInternalId1219 #up1220main: -- execute(" UPDATE ml_candidates\n SET internal_id = temp.internal_id_num\n FROM (\n SELECT id, ROW_NUMBER() OVER (PARTITION BY project_id ORDER BY internal_id, id ASC) AS internal_id_num\n FROM ml_candidates\n ) AS temp\n WHERE ml_candidates.id = temp.id\n")1221main: -> 0.0023s1222main: -- execute(" UPDATE ml_candidates\n SET internal_id = temp.internal_id_num\n FROM (\n SELECT id, ROW_NUMBER() OVER (PARTITION BY project_id ORDER BY internal_id, id ASC) AS internal_id_num\n FROM ml_candidates\n ) AS temp\n WHERE ml_candidates.id = temp.id\n")1223main: -> 0.0020s1224 sets the correct project_id with idempotency1225# [RSpecRunTime] RSpec elapsed time: 14 minutes 34.35 seconds. Current RSS: ~1357M. load average: 1.08 1.03 1.14 1/274 4011226INFO: analyzing "public.p_ci_builds" inheritance tree1227INFO: analyzing "public.ci_builds"1228INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1229I, [2024-03-04T13:45:11.763204 #338] INFO -- : Database: 'main', Table: 'p_ci_builds': Lock Writes1230I, [2024-03-04T13:45:11.764230 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1231I, [2024-03-04T13:45:11.765112 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1232I, [2024-03-04T13:45:25.942503 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1233INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1234INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1235INFO: "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 rows1236INFO: analyzing "public.p_ci_job_annotations" inheritance tree1237INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1238INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1239INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1240INFO: analyzing "public.ci_builds_metadata"1241INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1242INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1243INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1244INFO: analyzing "public.p_ci_builds" inheritance tree1245INFO: analyzing "public.ci_builds"1246INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1247I, [2024-03-04T13:45:51.029193 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1248INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1249INFO: analyzing "public.ci_pipeline_variables"1250INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1251I, [2024-03-04T13:46:11.996902 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1252I, [2024-03-04T13:46:11.998197 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1253I, [2024-03-04T13:46:11.999080 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1254I, [2024-03-04T13:46:12.479916 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1255INFO: 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-03-04T13:46:13.754628 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1259I, [2024-03-04T13:46:13.755649 #338] 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-03-04T13:46:13.756935 #338] 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_pipeline_variables" inheritance tree1262INFO: analyzing "public.ci_pipeline_variables"1263INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1264INFO: analyzing "public.p_ci_stages" inheritance tree1265INFO: analyzing "public.ci_stages"1266INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1267I, [2024-03-04T13:46:19.281513 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1268I, [2024-03-04T13:46:19.282630 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1269I, [2024-03-04T13:46:19.283628 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1270# [RSpecRunTime] Finishing example group spec/migrations/20230321170823_backfill_ml_candidates_internal_id_spec.rb. It took 2 minutes 33.33 seconds. Expected to take 1 minute 7.41 seconds.1271# [RSpecRunTime] RSpec elapsed time: 15 minutes 59.41 seconds. Current RSS: ~1344M. load average: 1.14 1.05 1.14 1/274 4021272# [RSpecRunTime] Starting example group spec/migrations/20230428085332_remove_shimo_zentao_integration_records_spec.rb. Expected to take 1 minute 1.73 seconds.1273RemoveShimoZentaoIntegrationRecords1274 with CE/EE env1275main: == [advisory_lock_connection] object_id: 205759360, pg_backend_pid: 41191276main: == 20230428085332 RemoveShimoZentaoIntegrationRecords: migrating ==============1277main: == 20230428085332 RemoveShimoZentaoIntegrationRecords: migrated (0.0365s) =====1278main: == [advisory_lock_connection] object_id: 205759360, pg_backend_pid: 41191279 destroys all shimo and zentao integrations1280# [RSpecRunTime] RSpec elapsed time: 17 minutes 4.08 seconds. Current RSS: ~1341M. load average: 1.08 1.05 1.13 1/274 4031281 with JiHu env1282main: == [advisory_lock_connection] object_id: 206863300, pg_backend_pid: 41211283main: == 20230428085332 RemoveShimoZentaoIntegrationRecords: migrating ==============1284main: == 20230428085332 RemoveShimoZentaoIntegrationRecords: migrated (0.0073s) =====1285main: == [advisory_lock_connection] object_id: 206863300, pg_backend_pid: 41211286 keeps shimo and zentao integrations1287# [RSpecRunTime] RSpec elapsed time: 17 minutes 7.17 seconds. Current RSS: ~1350M. load average: 1.07 1.05 1.13 1/274 4041288The application_settings (main) table has 1311 columns.1289Recreating the database1290Dropped database 'gitlabhq_test'1291Dropped database 'gitlabhq_test_ci'1292Created database 'gitlabhq_test'1293Created database 'gitlabhq_test_ci'1294main: == [advisory_lock_connection] object_id: 207324820, pg_backend_pid: 41311295main: == [advisory_lock_connection] object_id: 207324820, pg_backend_pid: 41311296ci: == [advisory_lock_connection] object_id: 207411220, pg_backend_pid: 41331297ci: == [advisory_lock_connection] object_id: 207411220, pg_backend_pid: 41331298Databases re-creation done in 11.7295081960000971299# [RSpecRunTime] Finishing example group spec/migrations/20230428085332_remove_shimo_zentao_integration_records_spec.rb. It took 1 minute 19.7 seconds. Expected to take 1 minute 1.73 seconds.1300# [RSpecRunTime] RSpec elapsed time: 17 minutes 19.14 seconds. Current RSS: ~1347M. load average: 1.06 1.05 1.13 1/274 4071301# [RSpecRunTime] Starting example group spec/migrations/20230605095810_ensure_default_organization_spec.rb. Expected to take 53.83 seconds.1302EnsureDefaultOrganization1303main: == [advisory_lock_connection] object_id: 215168580, pg_backend_pid: 41401304main: == 20230605095810 EnsureDefaultOrganization: migrating ========================1305main: == 20230605095810 EnsureDefaultOrganization: migrated (0.0207s) ===============1306main: == [advisory_lock_connection] object_id: 215168580, pg_backend_pid: 41401307 creates default organization if needed1308 when default organization already exists1309main: == [advisory_lock_connection] object_id: 216278740, pg_backend_pid: 41441310main: == 20230605095810 EnsureDefaultOrganization: migrating ========================1311main: == 20230605095810 EnsureDefaultOrganization: migrated (0.0082s) ===============1312main: == [advisory_lock_connection] object_id: 216278740, pg_backend_pid: 41441313 does not creates default organization if needed1314# [RSpecRunTime] RSpec elapsed time: 18 minutes 4.66 seconds. Current RSS: ~1330M. load average: 1.08 1.06 1.12 1/274 4081315 when the path is in use by another organization1316main: == [advisory_lock_connection] object_id: 216879600, pg_backend_pid: 41471317main: == 20230605095810 EnsureDefaultOrganization: migrating ========================1318main: == 20230605095810 EnsureDefaultOrganization: migrated (0.0208s) ===============1319main: == [advisory_lock_connection] object_id: 216879600, pg_backend_pid: 41471320 adds a random hash to the path1321# [RSpecRunTime] RSpec elapsed time: 18 minutes 7.73 seconds. Current RSS: ~1333M. load average: 1.00 1.04 1.12 1/274 4091322I, [2024-03-04T13:48:35.169239 #338] INFO -- : Database: 'main', Table: 'p_ci_job_annotations': Lock Writes1323INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1324INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1325INFO: "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 rows1326INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1327INFO: "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 rows1328INFO: analyzing "public.p_ci_job_annotations" inheritance tree1329INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1330INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1331INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1332INFO: analyzing "public.ci_builds_metadata"1333INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1334INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1335INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1336INFO: analyzing "public.p_ci_builds" inheritance tree1337INFO: analyzing "public.ci_builds"1338INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1339INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1340INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1341I, [2024-03-04T13:48:57.583734 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1342INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1343INFO: analyzing "public.ci_pipeline_variables"1344INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1345I, [2024-03-04T13:49:16.241059 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1346I, [2024-03-04T13:49:16.242189 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1347I, [2024-03-04T13:49:16.242970 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1348I, [2024-03-04T13:49:16.661316 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1349INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1350INFO: analyzing "public.ci_job_artifacts"1351INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1352I, [2024-03-04T13:49:17.857986 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1353I, [2024-03-04T13:49:17.859115 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1354I, [2024-03-04T13:49:17.860036 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1355INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1356INFO: analyzing "public.ci_pipeline_variables"1357INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1358INFO: analyzing "public.p_ci_stages" inheritance tree1359INFO: analyzing "public.ci_stages"1360INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1361I, [2024-03-04T13:49:23.234716 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1362I, [2024-03-04T13:49:23.235735 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1363I, [2024-03-04T13:49:23.236477 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1364# [RSpecRunTime] Finishing example group spec/migrations/20230605095810_ensure_default_organization_spec.rb. It took 1 minute 44.08 seconds. Expected to take 53.83 seconds.1365# [RSpecRunTime] RSpec elapsed time: 19 minutes 3.26 seconds. Current RSS: ~1333M. load average: 0.90 1.00 1.10 1/274 4101366# [RSpecRunTime] Starting example group spec/migrations/ensure_todos_bigint_backfill_completed_for_self_managed_spec.rb. Expected to take 46.04 seconds.1367EnsureTodosBigintBackfillCompletedForSelfManaged1368 #up1369main: == [advisory_lock_connection] object_id: 235442940, pg_backend_pid: 41611370main: == 20230710024518 EnsureTodosBigintBackfillCompletedForSelfManaged: migrating =1371main: == 20230710024518 EnsureTodosBigintBackfillCompletedForSelfManaged: migrated (0.0064s) 1372main: == [advisory_lock_connection] object_id: 235442940, pg_backend_pid: 41611373 ensures the migration is completed for self-managed instances1374main: == [advisory_lock_connection] object_id: 236302980, pg_backend_pid: 41641375main: == 20230710024518 EnsureTodosBigintBackfillCompletedForSelfManaged: migrating =1376main: == 20230710024518 EnsureTodosBigintBackfillCompletedForSelfManaged: migrated (0.0076s) 1377main: == [advisory_lock_connection] object_id: 236302980, pg_backend_pid: 41641378 skips the check for GitLab.com, dev, or test1379# [RSpecRunTime] RSpec elapsed time: 19 minutes 44.48 seconds. Current RSS: ~1341M. load average: 0.94 1.00 1.09 1/274 4111380INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1381INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1382INFO: "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 rows1383INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1384INFO: "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 rows1385INFO: analyzing "public.p_ci_job_annotations" inheritance tree1386INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1387INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1388INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1389INFO: analyzing "public.ci_builds_metadata"1390INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1391INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1392INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1393INFO: analyzing "public.p_ci_builds" inheritance tree1394INFO: analyzing "public.ci_builds"1395INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1396INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1397INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1398I, [2024-03-04T13:50:29.583737 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1399INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1400INFO: analyzing "public.ci_pipeline_variables"1401INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1402I, [2024-03-04T13:50:48.875505 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1403I, [2024-03-04T13:50:48.876557 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1404I, [2024-03-04T13:50:48.877481 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1405I, [2024-03-04T13:50:49.336815 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1406INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1407INFO: analyzing "public.ci_job_artifacts"1408INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1409I, [2024-03-04T13:50:50.629032 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1410I, [2024-03-04T13:50:50.630214 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1411I, [2024-03-04T13:50:50.630990 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1412INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1413INFO: analyzing "public.ci_pipeline_variables"1414INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1415INFO: analyzing "public.p_ci_stages" inheritance tree1416INFO: analyzing "public.ci_stages"1417INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1418I, [2024-03-04T13:50:56.339499 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1419I, [2024-03-04T13:50:56.340745 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1420I, [2024-03-04T13:50:56.341669 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1421# [RSpecRunTime] Finishing example group spec/migrations/ensure_todos_bigint_backfill_completed_for_self_managed_spec.rb. It took 1 minute 33.24 seconds. Expected to take 46.04 seconds.1422# [RSpecRunTime] RSpec elapsed time: 20 minutes 36.53 seconds. Current RSS: ~1333M. load average: 0.84 0.96 1.07 1/274 4121423# [RSpecRunTime] Starting example group spec/migrations/20230703024031_cleanup_project_pipeline_status_key_spec.rb. Expected to take 43.33 seconds.1424CleanupProjectPipelineStatusKey1425main: == [advisory_lock_connection] object_id: 253420040, pg_backend_pid: 41771426main: == 20230703024031 CleanupProjectPipelineStatusKey: migrating ==================1427main: == 20230703024031 CleanupProjectPipelineStatusKey: migrated (0.0090s) =========1428main: == [advisory_lock_connection] object_id: 253420040, pg_backend_pid: 41771429 enqueues a RedisMigrationWorker job from cursor 01430INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1431INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1432INFO: "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 rows1433INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1434INFO: "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 rows1435INFO: analyzing "public.p_ci_job_annotations" inheritance tree1436INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1437INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1438INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1439INFO: analyzing "public.ci_builds_metadata"1440INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1441INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1442INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1443INFO: analyzing "public.p_ci_builds" inheritance tree1444INFO: analyzing "public.ci_builds"1445INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1446INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1447INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1448I, [2024-03-04T13:52:03.314614 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1449INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1450INFO: analyzing "public.ci_pipeline_variables"1451INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1452I, [2024-03-04T13:52:22.924119 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1453I, [2024-03-04T13:52:22.925395 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1454I, [2024-03-04T13:52:22.926245 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1455I, [2024-03-04T13:52:23.405540 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1456INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1457INFO: analyzing "public.ci_job_artifacts"1458INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1459I, [2024-03-04T13:52:24.637840 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1460I, [2024-03-04T13:52:24.638877 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1461I, [2024-03-04T13:52:24.639651 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1462INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1463INFO: analyzing "public.ci_pipeline_variables"1464INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1465INFO: analyzing "public.p_ci_stages" inheritance tree1466INFO: analyzing "public.ci_stages"1467INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1468I, [2024-03-04T13:52:30.354421 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1469I, [2024-03-04T13:52:30.355606 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1470I, [2024-03-04T13:52:30.356940 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1471# [RSpecRunTime] Finishing example group spec/migrations/20230703024031_cleanup_project_pipeline_status_key_spec.rb. It took 1 minute 33.89 seconds. Expected to take 43.33 seconds.1472# [RSpecRunTime] RSpec elapsed time: 22 minutes 10.45 seconds. Current RSS: ~1327M. load average: 1.00 1.00 1.07 1/274 4131473# [RSpecRunTime] Starting example group spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb. Expected to take 40.29 seconds.1474AddCurrentUserTodosWidgetToEpicWorkItemType1475 behaves like migration that adds a widget to a work item type1476 #up1477main: == [advisory_lock_connection] object_id: 270439860, pg_backend_pid: 41901478main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======1479main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0151s) 1480main: == [advisory_lock_connection] object_id: 270439860, pg_backend_pid: 41901481 adds widget to work item type1482 when type does not exist1483main: == [advisory_lock_connection] object_id: 270867720, pg_backend_pid: 41931484main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======1485main: -- Epic work item type does not exist, skipping widget creation1486main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0126s) 1487main: == [advisory_lock_connection] object_id: 270867720, pg_backend_pid: 41931488 skips creating the new widget definition1489# [RSpecRunTime] RSpec elapsed time: 22 minutes 45.36 seconds. Current RSS: ~1328M. load average: 0.94 0.98 1.06 1/274 4141490 #down1491main: == [advisory_lock_connection] object_id: 271484320, pg_backend_pid: 41951492main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrating ======1493main: == 20230802212443 AddCurrentUserTodosWidgetToEpicWorkItemType: migrated (0.0145s) 1494main: == [advisory_lock_connection] object_id: 271484320, pg_backend_pid: 41951495 removes widget from work item type1496# [RSpecRunTime] RSpec elapsed time: 22 minutes 48.45 seconds. Current RSS: ~1320M. load average: 0.94 0.98 1.06 1/274 4151497INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1498INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1499INFO: "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 rows1500INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1501INFO: "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 rows1502INFO: analyzing "public.p_ci_job_annotations" inheritance tree1503INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1504INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1505INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1506INFO: analyzing "public.ci_builds_metadata"1507INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1508INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1509INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1510INFO: analyzing "public.p_ci_builds" inheritance tree1511INFO: analyzing "public.ci_builds"1512INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1513INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1514INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1515I, [2024-03-04T13:53:22.607532 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1516INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1517INFO: analyzing "public.ci_pipeline_variables"1518INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1519I, [2024-03-04T13:53:42.186967 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1520I, [2024-03-04T13:53:42.187971 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1521I, [2024-03-04T13:53:42.188691 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1522I, [2024-03-04T13:53:42.613061 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1523INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1524INFO: analyzing "public.ci_job_artifacts"1525INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1526I, [2024-03-04T13:53:43.851021 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1527I, [2024-03-04T13:53:43.852182 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1528I, [2024-03-04T13:53:43.852996 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1529INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1530INFO: analyzing "public.ci_pipeline_variables"1531INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1532INFO: analyzing "public.p_ci_stages" inheritance tree1533INFO: analyzing "public.ci_stages"1534INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1535I, [2024-03-04T13:53:49.960768 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1536I, [2024-03-04T13:53:49.961790 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1537I, [2024-03-04T13:53:49.962554 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1538# [RSpecRunTime] Finishing example group spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb. It took 1 minute 19.43 seconds. Expected to take 40.29 seconds.1539# [RSpecRunTime] RSpec elapsed time: 23 minutes 29.99 seconds. Current RSS: ~1321M. load average: 1.09 1.02 1.07 1/274 4161540# [RSpecRunTime] Starting example group spec/migrations/20240108072318_fix_invalid_records_ci_build_trace_metadata_spec.rb. Expected to take 34.07 seconds.1541FixInvalidRecordsCiBuildTraceMetadata1542 when FKs exist1543ci: == [advisory_lock_connection] object_id: 285769580, pg_backend_pid: 44691544ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrating ============1545ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrated (0.0380s) ===1546ci: == [advisory_lock_connection] object_id: 285769580, pg_backend_pid: 44691547 does not remove records1548# [RSpecRunTime] RSpec elapsed time: 23 minutes 57.01 seconds. Current RSS: ~1415M. load average: 1.29 1.06 1.08 1/274 4171549 with missing FKs1550ci: == [advisory_lock_connection] object_id: 287361800, pg_backend_pid: 44751551ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrating ============1552ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrated (0.0498s) ===1553ci: == [advisory_lock_connection] object_id: 287361800, pg_backend_pid: 44751554 removes orphan records1555 with invalid FKs1556ci: == [advisory_lock_connection] object_id: 288562340, pg_backend_pid: 44821557ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrating ============1558ci: == 20240108072318 FixInvalidRecordsCiBuildTraceMetadata: migrated (0.0449s) ===1559ci: == [advisory_lock_connection] object_id: 288562340, pg_backend_pid: 44821560 removes orphan records1561# [RSpecRunTime] RSpec elapsed time: 24 minutes 3.36 seconds. Current RSS: ~1398M. load average: 1.27 1.06 1.08 1/274 4181562INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1563INFO: analyzing "public.ci_pipeline_variables"1564INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1565I, [2024-03-04T13:54:33.078807 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1566I, [2024-03-04T13:54:33.079784 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1567I, [2024-03-04T13:54:33.080498 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1568I, [2024-03-04T13:54:33.541498 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1569INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1570INFO: analyzing "public.ci_job_artifacts"1571INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1572I, [2024-03-04T13:54:34.749611 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1573I, [2024-03-04T13:54:34.750674 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1574I, [2024-03-04T13:54:34.751539 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1575INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1576INFO: analyzing "public.ci_pipeline_variables"1577INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1578INFO: analyzing "public.p_ci_stages" inheritance tree1579INFO: analyzing "public.ci_stages"1580INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1581I, [2024-03-04T13:54:40.328508 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1582I, [2024-03-04T13:54:40.329538 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1583I, [2024-03-04T13:54:40.330264 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1584I, [2024-03-04T13:54:44.342858 #338] INFO -- : Database: 'ci', Table: 'ai_agents': Lock Writes1585I, [2024-03-04T13:54:44.373860 #338] INFO -- : Database: 'ci', Table: 'ai_agent_versions': Lock Writes1586I, [2024-03-04T13:54:45.520790 #338] INFO -- : Database: 'ci', Table: 'audit_events_group_external_streaming_destinations': Lock Writes1587I, [2024-03-04T13:54:45.700030 #338] INFO -- : Database: 'ci', Table: 'packages_terraform_module_metadata': Lock Writes1588I, [2024-03-04T13:54:45.745498 #338] INFO -- : Database: 'ci', Table: 'member_approvals': Lock Writes1589I, [2024-03-04T13:54:45.774032 #338] INFO -- : Database: 'ci', Table: 'zoekt_repositories': Lock Writes1590INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1591INFO: analyzing "public.ci_pipeline_variables"1592INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1593INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1594INFO: "ci_job_artifacts": scanned 1 of 1 pages, containing 0 live rows and 3 dead rows; 0 rows in sample, 0 estimated total rows1595INFO: analyzing "public.ci_job_artifacts"1596INFO: "ci_job_artifacts": scanned 1 of 1 pages, containing 0 live rows and 3 dead rows; 0 rows in sample, 0 estimated total rows1597INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1598INFO: analyzing "public.ci_pipeline_variables"1599INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1600I, [2024-03-04T13:54:49.801208 #338] INFO -- : Database: 'ci', Table: 'zoekt_tasks': Lock Writes1601I, [2024-03-04T13:54:50.008855 #338] INFO -- : Database: 'ci', Table: 'audit_events_instance_external_streaming_destinations': Lock Writes1602I, [2024-03-04T13:54:50.254229 #338] INFO -- : Database: 'ci', Table: 'catalog_verified_namespaces': Lock Writes1603I, [2024-03-04T13:54:50.451733 #338] INFO -- : Database: 'ci', Table: 'group_saved_replies': Lock Writes1604I, [2024-03-04T13:54:55.796677 #338] INFO -- : Database: 'ci', Table: 'remote_development_namespace_cluster_agent_mappings': Lock Writes1605I, [2024-03-04T13:54:55.797900 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1606I, [2024-03-04T13:54:55.798980 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1607INFO: analyzing "public.p_ci_stages" inheritance tree1608INFO: analyzing "public.ci_stages"1609INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1610I, [2024-03-04T13:54:57.165589 #338] INFO -- : Database: 'ci', Table: 'temp_notes_backup': Lock Writes1611# [RSpecRunTime] Finishing example group spec/migrations/20240108072318_fix_invalid_records_ci_build_trace_metadata_spec.rb. It took 1 minute 3.57 seconds. Expected to take 34.07 seconds.1612# [RSpecRunTime] RSpec elapsed time: 24 minutes 33.6 seconds. Current RSS: ~1378M. load average: 1.10 1.04 1.07 1/274 4191613# [RSpecRunTime] Starting example group spec/migrations/20230822104028_delete_project_callout_three_spec.rb. Expected to take 31.31 seconds.1614DeleteProjectCalloutThree1615main: == [advisory_lock_connection] object_id: 300338920, pg_backend_pid: 47521616main: == 20230822104028 DeleteProjectCalloutThree: migrating ========================1617main: -- execute("DELETE FROM user_project_callouts\nWHERE feature_name = 3\nAND id BETWEEN 1 AND 2\n")1618main: -> 0.0014s1619main: == 20230822104028 DeleteProjectCalloutThree: migrated (0.0241s) ===============1620main: == [advisory_lock_connection] object_id: 300338920, pg_backend_pid: 47521621 deletes only feature name 31622INFO: analyzing "public.p_ci_runner_machine_builds" inheritance tree1623INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_100"1624INFO: "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 rows1625INFO: analyzing "gitlab_partitions_dynamic.ci_runner_machine_builds_101"1626INFO: "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 rows1627INFO: analyzing "public.p_ci_job_annotations" inheritance tree1628INFO: analyzing "gitlab_partitions_dynamic.ci_job_annotations_100"1629INFO: "ci_job_annotations_100": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1630INFO: analyzing "public.p_ci_builds_metadata" inheritance tree1631INFO: analyzing "public.ci_builds_metadata"1632INFO: "ci_builds_metadata": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1633INFO: analyzing "gitlab_partitions_dynamic.ci_builds_metadata_101"1634INFO: "ci_builds_metadata_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1635INFO: analyzing "public.p_ci_builds" inheritance tree1636INFO: analyzing "public.ci_builds"1637INFO: "ci_builds": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1638INFO: analyzing "gitlab_partitions_dynamic.ci_builds_101"1639INFO: "ci_builds_101": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1640I, [2024-03-04T13:55:31.674111 #338] INFO -- : Database: 'main', Table: 'p_ci_finished_build_ch_sync_events': Lock Writes1641INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1642INFO: analyzing "public.ci_pipeline_variables"1643INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1644I, [2024-03-04T13:55:51.227211 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1645I, [2024-03-04T13:55:51.228214 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1646I, [2024-03-04T13:55:51.228979 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1647I, [2024-03-04T13:55:51.667590 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1648INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1649INFO: analyzing "public.ci_job_artifacts"1650INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1651I, [2024-03-04T13:55:52.942946 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1652I, [2024-03-04T13:55:52.944635 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1653I, [2024-03-04T13:55:52.945611 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1654INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1655INFO: analyzing "public.ci_pipeline_variables"1656INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1657INFO: analyzing "public.p_ci_stages" inheritance tree1658INFO: analyzing "public.ci_stages"1659INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1660I, [2024-03-04T13:55:58.687774 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1661I, [2024-03-04T13:55:58.689019 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1662I, [2024-03-04T13:55:58.689840 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1663# [RSpecRunTime] Finishing example group spec/migrations/20230822104028_delete_project_callout_three_spec.rb. It took 1 minute 5.11 seconds. Expected to take 31.31 seconds.1664# [RSpecRunTime] RSpec elapsed time: 25 minutes 38.74 seconds. Current RSS: ~1369M. load average: 1.09 1.04 1.07 1/274 4201665# [RSpecRunTime] Starting example group spec/migrations/20231103223224_backfill_zoekt_node_id_on_indexed_namespaces_spec.rb. Expected to take 26.41 seconds.1666BackfillZoektNodeIdOnIndexedNamespaces1667 #up1668main: == [advisory_lock_connection] object_id: 312048640, pg_backend_pid: 47641669main: == 20231103223224 BackfillZoektNodeIdOnIndexedNamespaces: migrating ===========1670main: -- execute(" UPDATE zoekt_indexed_namespaces\n SET zoekt_node_id = (SELECT id FROM zoekt_nodes ORDER BY created_at DESC LIMIT 1)\n")1671main: -> 0.0019s1672main: == 20231103223224 BackfillZoektNodeIdOnIndexedNamespaces: migrated (0.0099s) ==1673main: == [advisory_lock_connection] object_id: 312048640, pg_backend_pid: 47641674 backfills zoekt_node_id with zoekt_shard_id1675 when there is somehow more than one zoekt node1676main: == [advisory_lock_connection] object_id: 313283780, pg_backend_pid: 47661677main: == 20231103223224 BackfillZoektNodeIdOnIndexedNamespaces: migrating ===========1678main: -- execute(" UPDATE zoekt_indexed_namespaces\n SET zoekt_node_id = (SELECT id FROM zoekt_nodes ORDER BY created_at DESC LIMIT 1)\n")1679main: -> 0.0020s1680main: == 20231103223224 BackfillZoektNodeIdOnIndexedNamespaces: migrated (0.0119s) ==1681main: == [advisory_lock_connection] object_id: 313283780, pg_backend_pid: 47661682 uses the latest zoekt node1683# [RSpecRunTime] RSpec elapsed time: 26 minutes 1.7 seconds. Current RSS: ~1381M. load average: 1.12 1.06 1.08 1/275 4211684INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1685INFO: analyzing "public.ci_pipeline_variables"1686INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1687I, [2024-03-04T13:56:41.263066 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1688I, [2024-03-04T13:56:41.264188 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1689I, [2024-03-04T13:56:41.265393 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1690I, [2024-03-04T13:56:41.702561 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1691INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1692INFO: analyzing "public.ci_job_artifacts"1693INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1694I, [2024-03-04T13:56:42.950468 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1695I, [2024-03-04T13:56:42.951701 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1696I, [2024-03-04T13:56:42.952627 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1697INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1698INFO: analyzing "public.ci_pipeline_variables"1699INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1700INFO: analyzing "public.p_ci_stages" inheritance tree1701INFO: analyzing "public.ci_stages"1702INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1703I, [2024-03-04T13:56:49.056490 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1704I, [2024-03-04T13:56:49.057450 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1705I, [2024-03-04T13:56:49.058195 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1706# [RSpecRunTime] Finishing example group spec/migrations/20231103223224_backfill_zoekt_node_id_on_indexed_namespaces_spec.rb. It took 50.28 seconds. Expected to take 26.41 seconds.1707# [RSpecRunTime] RSpec elapsed time: 26 minutes 29.06 seconds. Current RSS: ~1358M. load average: 1.08 1.05 1.08 1/275 4221708# [RSpecRunTime] Starting example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. Expected to take 20.43 seconds.1709QueueBackfillMergeRequestDiffsProjectId1710main: == [advisory_lock_connection] object_id: 321976120, pg_backend_pid: 47781711main: == 20231114043522 QueueBackfillMergeRequestDiffsProjectId: migrating ==========1712main: == 20231114043522 QueueBackfillMergeRequestDiffsProjectId: migrated (0.0501s) =1713main: == [advisory_lock_connection] object_id: 321976120, pg_backend_pid: 47781714 schedules a new batched migration1715INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1716INFO: analyzing "public.ci_pipeline_variables"1717INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1718I, [2024-03-04T13:57:26.636263 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1719I, [2024-03-04T13:57:26.637626 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1720I, [2024-03-04T13:57:26.638402 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1721I, [2024-03-04T13:57:27.047042 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1722INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1723INFO: analyzing "public.ci_job_artifacts"1724INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1725I, [2024-03-04T13:57:28.259191 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1726I, [2024-03-04T13:57:28.260315 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1727I, [2024-03-04T13:57:28.261174 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1728INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1729INFO: analyzing "public.ci_pipeline_variables"1730INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1731INFO: analyzing "public.p_ci_stages" inheritance tree1732INFO: analyzing "public.ci_stages"1733INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1734I, [2024-03-04T13:57:34.004742 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1735I, [2024-03-04T13:57:34.005792 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1736I, [2024-03-04T13:57:34.006726 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1737# [RSpecRunTime] Finishing example group spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb. It took 44.91 seconds. Expected to take 20.43 seconds.1738# [RSpecRunTime] RSpec elapsed time: 27 minutes 14.01 seconds. Current RSS: ~1359M. load average: 1.04 1.04 1.07 1/275 4231739# [RSpecRunTime] Starting example group spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb. Expected to take 13.44 seconds.1740QueueDropVulnerabilitiesWithoutFindingId1741main: == [advisory_lock_connection] object_id: 329656180, pg_backend_pid: 47901742main: == 20231221223259 QueueDropVulnerabilitiesWithoutFindingId: migrating =========1743main: == 20231221223259 QueueDropVulnerabilitiesWithoutFindingId: migrated (0.0539s) 1744main: == [advisory_lock_connection] object_id: 329656180, pg_backend_pid: 47901745 schedules a new batched migration1746INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1747INFO: analyzing "public.ci_pipeline_variables"1748INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1749I, [2024-03-04T13:57:57.242472 #338] INFO -- : Database: 'main', Table: 'p_ci_pipeline_variables': Lock Writes1750I, [2024-03-04T13:57:57.243576 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1751I, [2024-03-04T13:57:57.244339 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1752I, [2024-03-04T13:57:57.669144 #338] INFO -- : Database: 'main', Table: 'ci_job_token_group_scope_links': Lock Writes1753INFO: analyzing "public.p_ci_job_artifacts" inheritance tree1754INFO: analyzing "public.ci_job_artifacts"1755INFO: "ci_job_artifacts": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1756I, [2024-03-04T13:57:58.811791 #338] INFO -- : Database: 'main', Table: 'p_ci_job_artifacts': Lock Writes1757I, [2024-03-04T13:57:58.813220 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1758I, [2024-03-04T13:57:58.813934 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1759INFO: analyzing "public.p_ci_pipeline_variables" inheritance tree1760INFO: analyzing "public.ci_pipeline_variables"1761INFO: "ci_pipeline_variables": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1762INFO: analyzing "public.p_ci_stages" inheritance tree1763INFO: analyzing "public.ci_stages"1764INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1765I, [2024-03-04T13:58:04.526066 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1766I, [2024-03-04T13:58:04.527180 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1767I, [2024-03-04T13:58:04.528000 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1768# [RSpecRunTime] Finishing example group spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb. It took 30.68 seconds. Expected to take 13.44 seconds.1769# [RSpecRunTime] RSpec elapsed time: 27 minutes 44.72 seconds. Current RSS: ~1345M. load average: 1.02 1.04 1.07 1/275 4241770# [RSpecRunTime] Starting example group spec/migrations/20240226174509_queue_backfill_onboarding_status_step_url_spec.rb. Expected to take 4.18 seconds.1771QueueBackfillOnboardingStatusStepUrl1772main: == [advisory_lock_connection] object_id: 334779920, pg_backend_pid: 48011773main: == 20240226174509 QueueBackfillOnboardingStatusStepUrl: migrating =============1774main: == 20240226174509 QueueBackfillOnboardingStatusStepUrl: migrated (0.0564s) ====1775main: == [advisory_lock_connection] object_id: 334779920, pg_backend_pid: 48011776 schedules a new batched migration1777# [RSpecRunTime] Finishing example group spec/migrations/20240226174509_queue_backfill_onboarding_status_step_url_spec.rb. It took 9.75 seconds. Expected to take 4.18 seconds.1778# [RSpecRunTime] RSpec elapsed time: 27 minutes 54.5 seconds. Current RSS: ~1337M. load average: 1.02 1.03 1.07 1/275 4251779# [RSpecRunTime] Starting example group spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb. Missing expected duration from Knapsack report for spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb.1780EnsureMemberRolesNamesUniq1781 #up1782main: -- execute("UPDATE member_roles SET name = CONCAT(name, ' (', id, ')')\nWHERE id IN (\n SELECT mr.id FROM member_roles mr\n WHERE EXISTS (SELECT mr_duplicates.id\n FROM member_roles mr_duplicates\n WHERE mr_duplicates.name = mr.name\n AND (\n mr_duplicates.namespace_id = mr.namespace_id\n OR (mr_duplicates.namespace_id IS NULL AND mr.namespace_id IS NULL)\n )\n AND mr_duplicates.id < mr.id))\n")1783main: -> 0.0023s1784main: -- execute("UPDATE member_roles SET name = CONCAT(name, ' (', id, ')')\nWHERE id IN (\n SELECT mr.id FROM member_roles mr\n WHERE EXISTS (SELECT mr_duplicates.id\n FROM member_roles mr_duplicates\n WHERE mr_duplicates.name = mr.name\n AND (\n mr_duplicates.namespace_id = mr.namespace_id\n OR (mr_duplicates.namespace_id IS NULL AND mr.namespace_id IS NULL)\n )\n AND mr_duplicates.id < mr.id))\n")1785main: -> 0.0020s1786 updates the duplicated names with higher id1787# [RSpecRunTime] RSpec elapsed time: 28 minutes 0.05 second. Current RSS: ~1353M. load average: 1.02 1.03 1.07 1/275 4261788INFO: analyzing "public.p_ci_stages" inheritance tree1789INFO: analyzing "public.ci_stages"1790INFO: "ci_stages": scanned 0 of 0 pages, containing 0 live rows and 0 dead rows; 0 rows in sample, 0 estimated total rows1791I, [2024-03-04T13:58:29.671003 #338] INFO -- : Database: 'main', Table: 'p_ci_stages': Lock Writes1792I, [2024-03-04T13:58:29.672077 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Lock timeout is set", :current_iteration=>1, :lock_timeout_in_ms=>100}1793I, [2024-03-04T13:58:29.672858 #338] INFO -- : {:method=>"with_lock_retries", :class=>"gitlab:db:lock_writes", :message=>"Migration finished", :current_iteration=>1, :lock_timeout_in_ms=>100}1794# [RSpecRunTime] Finishing example group spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb. It took 15.15 seconds. Missing expected duration from Knapsack report for spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb.1795# [RSpecRunTime] RSpec elapsed time: 28 minutes 9.68 seconds. Current RSS: ~1332M. load average: 0.94 1.01 1.06 1/275 4271796auto_explain log contains 935 entries for main, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-1-15.338.main.ndjson.gz1797took 17.2565717611798auto_explain log contains 935 entries for ci, writing to /builds/gitlab-org/gitlab/auto_explain/rspec-migration-pg14-1-15.338.ci.ndjson.gz1799took 13.6797223231800[TEST PROF INFO] EventProf results for sql.active_record1801Total time: 20:43.755 of 28:05.677 (73.78%)1802Total events: 8108151803Top 5 slowest suites (by time):1804FinalizeCiBui...gIntConversion (./spec/migrations/20230214181633_finalize_ci_build_needs_big_int_conversion_spec.rb:6) – 04:19.324 (191953 / 8) of 06:17.921 (68.62%)1805BackfillMlCandidatesInternalId (./spec/migrations/20230321170823_backfill_ml_candidates_internal_id_spec.rb:7) – 02:00.431 (63507 / 1) of 02:33.362 (78.53%)1806EnsureNoteDif...orGitlabDotCom (./spec/migrations/ensure_note_diff_files_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb:6) – 01:54.314 (65373 / 2) of 02:28.637 (76.91%)1807SwapTodosNote...orGitlabDotCom (./spec/migrations/swap_todos_note_id_to_bigint_for_gitlab_dot_com_spec.rb:6) – 01:50.486 (60922 / 2) of 02:20.833 (78.45%)1808RemoveOldAsyn...ngthConstraint (./spec/migrations/remove_old_async_index_table_name_length_constraint_spec.rb:6) – 01:46.474 (57893 / 3) of 02:14.648 (79.08%)1809Knapsack report was generated. Preview:1810{1811 "spec/migrations/20230214181633_finalize_ci_build_needs_big_int_conversion_spec.rb": 195.9851122780001,1812 "spec/migrations/ensure_note_diff_files_bigint_backfill_is_finished_for_gitlab_dot_com_spec.rb": 67.22387780099962,1813 "spec/migrations/swap_todos_note_id_to_bigint_for_gitlab_dot_com_spec.rb": 66.33821010600059,1814 "spec/migrations/remove_old_async_index_table_name_length_constraint_spec.rb": 64.20532055300009,1815 "spec/migrations/20230321170823_backfill_ml_candidates_internal_id_spec.rb": 68.41972082199936,1816 "spec/migrations/20230428085332_remove_shimo_zentao_integration_records_spec.rb": 67.85665625599995,1817 "spec/migrations/20230605095810_ensure_default_organization_spec.rb": 48.6847590870002,1818 "spec/migrations/ensure_todos_bigint_backfill_completed_for_self_managed_spec.rb": 41.319791940999494,1819 "spec/migrations/20230703024031_cleanup_project_pipeline_status_key_spec.rb": 40.22196683399943,1820 "spec/migrations/20230802212443_add_current_user_todos_widget_to_epic_work_item_type_spec.rb": 38.067754858000626,1821 "spec/migrations/20240108072318_fix_invalid_records_ci_build_trace_metadata_spec.rb": 33.452482155000325,1822 "spec/migrations/20230822104028_delete_project_callout_three_spec.rb": 28.383378931000152,1823 "spec/migrations/20231103223224_backfill_zoekt_node_id_on_indexed_namespaces_spec.rb": 23.033899813999597,1824 "spec/migrations/20231115025547_queue_backfill_merge_request_diffs_project_id_spec.rb": 19.395532860999992,1825 "spec/migrations/20231221223259_queue_drop_vulnerabilities_without_finding_id_spec.rb": 12.546339099999386,1826 "spec/migrations/20240226174509_queue_backfill_onboarding_status_step_url_spec.rb": 3.481998979000309,1827 "spec/migrations/20240210104125_ensure_member_roles_names_uniq_spec.rb": 5.6290971769994941828}1829Knapsack global time execution for tests: 13m 44s1830Finished in 28 minutes 41 seconds (files took 42.13 seconds to load)183137 examples, 0 failures1832Randomized with seed 245991833[TEST PROF INFO] Time spent in factories: 00:00.280 (0.02% of total time)1834RSpec exited with 0.1835No examples to retry, congrats!1837Running after script...1838$ echo -e "\e[0Ksection_start:`date +%s`:report_results_section[collapsed=true]\r\e[0KReport results"1850$ tooling/bin/push_job_metrics || true1851[job-metrics] Pushing job metrics file for the CI/CD job.1852[job-metrics] Pushed 4 CI job metric entries to InfluxDB.1854Not uploading cache ruby-gems-debian-bookworm-ruby-3.1-17 due to policy1856Uploading artifacts...1857auto_explain/: found 3 matching artifact files and directories 1858coverage/: found 5 matching artifact files and directories 1859crystalball/: found 2 matching artifact files and directories 1860WARNING: deprecations/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1861knapsack/: found 4 matching artifact files and directories 1862rspec/: found 16 matching artifact files and directories 1863WARNING: tmp/capybara/: no matching files. Ensure that the artifact path is relative to the working directory (/builds/gitlab-org/gitlab) 1864log/*.log: found 13 matching artifact files and directories 1865WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6311411054/artifacts?artifact_format=zip&artifact_type=archive&expire_in=31d new-url=https://gitlab.com1866WARNING: Retrying... context=artifacts-uploader error=request redirected1867Uploading artifacts as "archive" to coordinator... 201 Created id=6311411054 responseStatus=201 Created token=glcbt-651868Uploading artifacts...1869rspec/rspec-*.xml: found 1 matching artifact files and directories 1870WARNING: Upload request redirected location=https://gitlab.com/api/v4/jobs/6311411054/artifacts?artifact_format=gzip&artifact_type=junit&expire_in=31d new-url=https://gitlab.com1871WARNING: Retrying... context=artifacts-uploader error=request redirected1872Uploading artifacts as "junit" to coordinator... 201 Created id=6311411054 responseStatus=201 Created token=glcbt-651874Job succeeded